[Serdev] TCP Blocking Connect issue
Andrew Mee
andrew at healthshare.net.au
Mon Aug 23 23:10:30 UTC 2004
Tried this in my config to no avail....
However I'm attaching the DEBUG log file and I noticed the following:
serctl ul show support at 192.168.2.253
<sip:192.168.2.11:7887;transport=tcp>;q=0.00;expires=3541 <-- Bad
<sip:192.168.2.11:8346;transport=tcp>;q=0.00;expires=3598 <-- Good
But this line occurs....
14(10871) check_self - checking if port 5060 matches port 5060
14(10871) rwrite(): Rewriting Request-URI with
'sip:192.168.2.11:7887;transport=tcp'
anyway have a look through the debug, and my current SER config. The
NGREP logs are at the bottom of the debug file
Thank-you for your patience with this btw!
Andrew
Jiri Kuthan wrote:
>Andrew,
>
>Here is a possible problem explanation -- Messenger is using maddr in Routes.
>Odd (RFC-2543 hack to deal with the strict-routing imperfection) but legal.
>Try:
>
> if (loose_route()) {
> subst_uri("/(sip:)([a-zA-Z0-9_.!~*'()%&=+$,;?-]+@)?([a-zA-Z0-9.-]+)(:[0-9]+)?(;.*)(maddr=)([0-9.]{7,15})/\1\2\7\4\5\6\7/");
>
>You should then see packets to 192.168.2.11.
>
>
>-jiri
>
>At 08:47 AM 8/23/2004, Juha Heinanen wrote:
>
>
>>Andrew Mee writes:
>>
>>
>>
>>>T 192.168.2.28:1148 -> 192.168.2.253:5060 [AP]
>>> ACK
>>>sip:support at 192.168.2.253;transport=tcp;ftag=ac5d7c39647143ed8365ef1c5f
>>> aaaf16;lr=on SIP/2.0..Via: SIP/2.0/TCP
>>>192.168.2.28:16532..Max-Forwards: 70
>>> ..From: "HS User"
>>><sip:test2 at 192.168.2.253>;tag=ac5d7c39647143ed8365ef1c5fa
>>> aaf16;epid=a98f8fc72f..To:
>>><sip:support at 192.168.2.253>;tag=04445215ea6d4c70
>>> b7c5bdadd5db0598..Call-ID:
>>>793c8868bce64e82b7e097dd204d57f6 at 192.168.2.28..C
>>> Seq: 1 ACK..Route:
>>><sip:support at 192.168.2.253:16501;maddr=192.168.2.11;tran
>>> sport=tcp>..User-Agent: RTC/1.2..Content-Length: 0....
>>>
>>> From here it tries to (as assumed) ACK to 192.168.2.11:10227 and then
>>>falls down... instead of SER realising that 192.168.2.11:10227 doesn't
>>>exist and then trying 192.168.2.11:16501, it just fails.
>>>
>>>
>>as jiri said, when ser routes ack, it doesn't do any location lookup.
>>it just forwards the ack according to route header which specifies:
>>
>><sip:support at 192.168.2.253:16501;maddr=192.168.2.11;transport=tcp>
>>
>>this is in conformance with rfc3261.
>>
>>-- juha
>>
>>_______________________________________________
>>Serdev mailing list
>>serdev at lists.iptel.org
>>http://lists.iptel.org/mailman/listinfo/serdev
>>
>>
>
>--
>Jiri Kuthan http://iptel.org/~jiri/
>
>
>
--
Andrew Mee
-------------- next part --------------
19(10881) tcp_main_loop: reader response= 402d19a0, 0 from 5
19(10881) tcp_main_loop: CONN_RELEASE 0x402d19a0 refcnt= 0
14(10871) tcp_read_req: content-length= 743
14(10871) SIP Request:
14(10871) method: <INVITE>
14(10871) uri: <sip:support at 192.168.2.253>
14(10871) version: <SIP/2.0>
14(10871) parse_headers: flags=1
14(10871) end of header reached, state=5
14(10871) parse_headers: Via found, flags=1
14(10871) parse_headers: this is the first via
14(10871) After parse_msg...
14(10871) preparing to run routing scripts...
14(10871) DEBUG : is_maxfwd_present: searching for max_forwards header
14(10871) parse_headers: flags=128
14(10871) DEBUG: is_maxfwd_present: value = 70
14(10871) parse_headers: flags=8
14(10871) DEBUG: add_param: tag=455e2e10b10e464cafe24f3603111429
14(10871) DEBUG: add_param: epid=c83d59757b
14(10871) end of header reached, state=29
14(10871) parse_headers: flags=256
14(10871) end of header reached, state=9
14(10871) DEBUG: get_hdr_field: <To> [29]; uri=[sip:support at 192.168.2.253]
14(10871) DEBUG: to body [<sip:support at 192.168.2.253>
]
14(10871) get_hdr_field: cseq <CSeq>: <1> <INVITE>
14(10871) DEBUG: get_hdr_body : content_length=743
14(10871) found end of header
14(10871) find_first_route(): No Route headers found
14(10871) loose_route(): There is no Route HF
14(10871) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
14(10871) check_self - checking if port 5060 matches port 5060
14(10871) rwrite(): Rewriting Request-URI with 'sip:192.168.2.11:7887;transport=tcp'
14(10871) ---------- PROCESSING INVITE/ACK!!!!
14(10871) parse_headers: flags=-1
14(10871) parse_headers: flags=60
14(10871) DEBUG: MD5 calculated: 27e5a4f5844c10315b09ab4057337a61
14(10871) DEBUG: print_var: SIP_HF_CONTENT_LENGTH=743
14(10871) DEBUG: print_var: SIP_HF_CONTENT_TYPE=application/sdp
14(10871) DEBUG: print_var: SIP_HF_USER_AGENT=RTC/1.2
14(10871) DEBUG: print_var: SIP_HF_CONTACT=<sip:test2 at 192.168.2.253:16774;maddr=192.168.2.28;transport=tcp>
14(10871) DEBUG: print_var: SIP_HF_CSEQ=1 INVITE
14(10871) DEBUG: print_var: SIP_HF_CALLID=def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28
14(10871) DEBUG: print_var: SIP_HF_TO=<sip:support at 192.168.2.253>
14(10871) DEBUG: print_var: SIP_HF_FROM="HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b
14(10871) DEBUG: print_var: SIP_HF_MAX_FORWARDS=69
14(10871) DEBUG: print_var: SIP_HF_VIA=SIP/2.0/TCP 192.168.2.28:16774
14(10871) INFO: SIGCHLD received: we do not worry about grand-children
14(10871) DEBUG: t_addifnew: msg id=5 , global msg id=4 , T on entrance=0xffffffff
14(10871) parse_headers: flags=-1
14(10871) parse_headers: flags=60
14(10871) t_lookup_request: start searching: hash=26290, isACK=0
14(10871) DEBUG: proceeding to pre-RFC3261 transaction matching
14(10871) DEBUG: t_lookup_request: no transaction found
14(10871) SER: new INVITE
14(10871) parse_headers: flags=-1
14(10871) check_via_address(192.168.2.28, 192.168.2.28, 0)
14(10871) WARNING:vqm_resize: resize(0) called
14(10871) tcp_send: tcp connection found (0x402e1bb0), acquiring fd
14(10871) tcp_send, c= 0x402e1bb0, n=8
19(10881) tcp_main_loop: read response= 402e1bb0, 1 from 14 (10871)
14(10871) tcp_send: after receive_fd: c= 0x402e1bb0 n=4 fd=27
14(10871) tcp_send: sending...
14(10871) tcp_send: after write: c= 0x402e1bb0 n=559 fd=27
14(10871) tcp_send: buf=
SIP/2.0 100 trying -- your call is important to us
Via: SIP/2.0/TCP 192.168.2.28:16774
From: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b
To: <sip:support at 192.168.2.253>
Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28
CSeq: 1 INVITE
Server: Sip EXpress router (0.8.14 (i386/linux))
Content-Length: 0
Warning: 392 192.168.2.253:5060 "Noisy feedback tells: pid=10871 req_src_ip=192.168.2.28 req_src_port=1071 in_uri=sip:support at 192.168.2.253 out_uri=sip:192.168.2.11:7887;transport=tcp via_cnt==1"
14(10871) DEBUG: reply sent out. buf=0x80c8fd0: SIP/2.0 1..., shmem=0x403043f0: SIP/2.0 1
14(10871) DEBUG: t_reply: finished
14(10871) DEBUG: mk_proxy: doing DNS lookup...
14(10871) build_req_from_req: id added: <;i=6>, rcv proto=2
14(10871) parse_headers: flags=2048
14(10871) check_via_address(192.168.2.28, 192.168.2.28, 0)
14(10871) DEBUG: mk_proxy: doing DNS lookup...
14(10871) build_req_from_req: id added: <;i=6>, rcv proto=2
14(10871) parse_headers: flags=2048
14(10871) check_via_address(192.168.2.28, 192.168.2.28, 0)
14(10871) tcp_send: no open tcp connection found, opening new one
14(10871) ERROR: tcp_blocking_connect: SO_ERROR (111) Connection refused
14(10871) ERROR: tcpconn_connect: tcp_blocking_connect failed
14(10871) ERROR: tcp_send: connect failed
14(10871) msg_send: ERROR: tcp_send failed
14(10871) ERROR: t_forward_nonack: sending request failed
14(10871) DEBUG: add_to_tail_of_timer[0]: 0x40303974
14(10871) tcp_send: no open tcp connection found, opening new one
14(10871) tcpconn_new: new tcp connection: 192.168.2.11
14(10871) tcpconn_new: on port 8346, type 2
14(10871) tcp_send: sending...
14(10871) tcp_send: after write: c= 0x403051f0 n=1368 fd=27
14(10871) tcp_send: buf=
INVITE sip:192.168.2.11:8346;transport=tcp SIP/2.0
Record-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603111429;lr=on>
Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK2b66.68f50de5.1;i=6
Via: SIP/2.0/TCP 192.168.2.28:16774
Max-Forwards: 69
From: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b
To: <sip:support at 192.168.2.253>
Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28
CSeq: 1 INVITE
Contact: <sip:test2 at 192.168.2.253:16774;maddr=192.168.2.28;transport=tcp>
User-Agent: RTC/1.2
Content-Type: application/sdp
Content-Length: 743
v=0
o=- 0 0 IN IP4 192.168.2.28
s=session
c=IN IP4 192.168.2.28
b=CT:1000
t=0 0
m=audio 30948 RTP/AVP 97 111 112 6 0 8 4 5 3 101
k=base64:XiIN5Y95v66MtouJLXx6mA85W2ixQmXrwGBs2tkqy/k
a=rtpmap:97 red/8000
a=rtpmap:111 SIREN/16000
a=fmtp:111 bitrate=16000
a=rtpmap:112 G7221/16000
a=fmtp:112 bitrate=24000
a=rtpmap:6 DVI4/16000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:5 DVI4/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=encryption:optional
m=video 16194 RTP/AVP 34 31
k=base64:how2kEmp54eFkxnMWyFIgrg6q6QcL8vXMnfuTiATTcU
a=rtpmap:34 H263/90000
a=rtpmap:31 H261/90000
a=encryption:optional
m=application 1503 tcp msdata
a=sendonly
a=encryption:optional
14(10871) DEBUG: add_to_tail_of_timer[0]: 0x40303a54
14(10871) SER: new transaction fwd'ed
14(10871) DEBUG:destroy_avp_list: destroing list (nil)
14(10871) receive_msg: cleaning up
19(10881) tcp_main_loop: read response= 403051f0, 2 from 14 (10871)
19(10881) tcpconn_add: hashes: 90, 10
19(10881) tcp_main_loop: data available on 0x403051f0 [h:90] 33
19(10881) send2child: to tcp child 0 11(10865), 0x403051f0
11(10865) received n=4 con=0x403051f0, fd=20
11(10865) tcp_read_req: content-length= 0
11(10865) SIP Reply (status):
11(10865) version: <SIP/2.0>
11(10865) status: <100>
11(10865) reason: <Trying>
11(10865) parse_headers: flags=1
11(10865) Found param type 232, <branch> = <z9hG4bK2b66.68f50de5.1>; state=611(10865) Found param type 236, <i> = <6>; state=16
11(10865) end of header reached, state=5
11(10865) parse_headers: Via found, flags=1
11(10865) parse_headers: this is the first via
11(10865) After parse_msg...
11(10865) forward_reply: found module tm, passing reply to it
11(10865) DEBUG: t_check: msg id=11 global id=7 T start=0xffffffff
11(10865) parse_headers: flags=17
11(10865) end of header reached, state=5
11(10865) parse_headers: Via found, flags=17
11(10865) parse_headers: this is the second via
11(10865) DEBUG: add_param: tag=081ad1daf478474abd2df8a5c6b2c442
11(10865) end of header reached, state=29
11(10865) DEBUG: get_hdr_field: <To> [66]; uri=[sip:support at 192.168.2.253]
11(10865) DEBUG: to body [<sip:support at 192.168.2.253>]
11(10865) get_hdr_field: cseq <CSeq>: <1> <INVITE>
11(10865) parse_headers: flags=4
11(10865) DEBUG: t_reply_matching: hash 26290 label 1590714246 branch 1
11(10865) DEBUG: t_reply_matching: reply matched (T=0x40303838)!
11(10865) DEBUG: t_check: msg id=11 global id=11 T end=0x40303838
11(10865) DEBUG: t_on_reply: org. status uas=100, uac[1]=0 local=0 is_invite=1)
11(10865) ->>>>>>>>> T_code=100, new_code=100
11(10865) DEBUG: relay_reply: branch=1, save=0, relay=-1
11(10865) DEBUG: add_to_tail_of_timer[1]: 0x40303a54
11(10865) DEBUG:destroy_avp_list: destroing list (nil)
11(10865) receive_msg: cleaning up
11(10865) tcp_read_req: content-length= 0
11(10865) SIP Reply (status):
11(10865) version: <SIP/2.0>
11(10865) status: <180>
11(10865) reason: <Ringing>
11(10865) parse_headers: flags=1
11(10865) Found param type 232, <branch> = <z9hG4bK2b66.68f50de5.1>; state=611(10865) Found param type 236, <i> = <6>; state=16
11(10865) end of header reached, state=5
11(10865) parse_headers: Via found, flags=1
11(10865) parse_headers: this is the first via
11(10865) After parse_msg...
11(10865) forward_reply: found module tm, passing reply to it
11(10865) DEBUG: t_check: msg id=12 global id=11 T start=0xffffffff
11(10865) parse_headers: flags=17
11(10865) end of header reached, state=5
11(10865) parse_headers: Via found, flags=17
11(10865) parse_headers: this is the second via
11(10865) DEBUG: add_param: tag=081ad1daf478474abd2df8a5c6b2c442
11(10865) end of header reached, state=29
11(10865) DEBUG: get_hdr_field: <To> [66]; uri=[sip:support at 192.168.2.253]
11(10865) DEBUG: to body [<sip:support at 192.168.2.253>]
11(10865) get_hdr_field: cseq <CSeq>: <1> <INVITE>
11(10865) parse_headers: flags=4
11(10865) DEBUG: t_reply_matching: hash 26290 label 1590714246 branch 1
11(10865) DEBUG: t_reply_matching: reply matched (T=0x40303838)!
11(10865) DEBUG: t_check: msg id=12 global id=12 T end=0x40303838
11(10865) DEBUG: t_on_reply: org. status uas=100, uac[1]=100 local=0 is_invite=1)
11(10865) ->>>>>>>>> T_code=100, new_code=180
11(10865) DEBUG: relay_reply: branch=1, save=0, relay=1
11(10865) parse_headers: flags=2048
11(10865) DEBUG: get_hdr_body : content_length=0
11(10865) old size: 505, new size: 439
11(10865) build_res_from_sip_res: copied size: orig:87, new: 21, rest: 418 msg=
SIP/2.0 180 Ringing
Via: SIP/2.0/TCP 192.168.2.28:16774
From: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b
To: <sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442
Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28
CSeq: 1 INVITE
Record-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603111429;lr=on>
User-Agent: RTC/1.2
Content-Length: 0
11(10865) tcp_send: tcp connection found (0x402e1bb0), acquiring fd
11(10865) tcp_send, c= 0x402e1bb0, n=8
19(10881) tcp_main_loop: read response= 402e1bb0, 1 from 11 (10865)
11(10865) tcp_send: after receive_fd: c= 0x402e1bb0 n=4 fd=21
11(10865) tcp_send: sending...
11(10865) tcp_send: after write: c= 0x402e1bb0 n=439 fd=21
11(10865) tcp_send: buf=
SIP/2.0 180 Ringing
Via: SIP/2.0/TCP 192.168.2.28:16774
From: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b
To: <sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442
Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28
CSeq: 1 INVITE
Record-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603111429;lr=on>
User-Agent: RTC/1.2
Content-Length: 0
11(10865) DEBUG: reply relayed. buf=0x80c93a0: SIP/2.0 1..., shmem=0x403043f0: SIP/2.0 1
11(10865) DEBUG: add_to_tail_of_timer[1]: 0x40303a54
11(10865) DEBUG:destroy_avp_list: destroing list (nil)
11(10865) receive_msg: cleaning up
11(10865) tcp_read_req: content-length= 743
11(10865) SIP Reply (status):
11(10865) version: <SIP/2.0>
11(10865) status: <200>
11(10865) reason: <OK>
11(10865) parse_headers: flags=1
11(10865) Found param type 232, <branch> = <z9hG4bK2b66.68f50de5.1>; state=611(10865) Found param type 236, <i> = <6>; state=16
11(10865) end of header reached, state=5
11(10865) parse_headers: Via found, flags=1
11(10865) parse_headers: this is the first via
11(10865) After parse_msg...
11(10865) forward_reply: found module tm, passing reply to it
11(10865) DEBUG: t_check: msg id=13 global id=12 T start=0xffffffff
11(10865) parse_headers: flags=17
11(10865) end of header reached, state=5
11(10865) parse_headers: Via found, flags=17
11(10865) parse_headers: this is the second via
11(10865) DEBUG: add_param: tag=081ad1daf478474abd2df8a5c6b2c442
11(10865) end of header reached, state=29
11(10865) DEBUG: get_hdr_field: <To> [66]; uri=[sip:support at 192.168.2.253]
11(10865) DEBUG: to body [<sip:support at 192.168.2.253>]
11(10865) get_hdr_field: cseq <CSeq>: <1> <INVITE>
11(10865) parse_headers: flags=4
11(10865) DEBUG: t_reply_matching: hash 26290 label 1590714246 branch 1
11(10865) DEBUG: t_reply_matching: reply matched (T=0x40303838)!
11(10865) DEBUG: t_check: msg id=13 global id=13 T end=0x40303838
11(10865) DEBUG: t_on_reply: org. status uas=180, uac[1]=180 local=0 is_invite=1)
11(10865) ->>>>>>>>> T_code=180, new_code=200
11(10865) DEBUG: relay_reply: branch=1, save=0, relay=1
11(10865) parse_headers: flags=2048
11(10865) DEBUG: get_hdr_body : content_length=743
11(10865) old size: 1352, new size: 1286
11(10865) build_res_from_sip_res: copied size: orig:82, new: 16, rest: 1270 msg=
SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.2.28:16774
From: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b
To: <sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442
Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28
CSeq: 1 INVITE
Record-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603111429;lr=on>
Contact: <sip:support at 192.168.2.253:8346;maddr=192.168.2.11;transport=tcp>
User-Agent: RTC/1.2
Content-Type: application/sdp
Content-Length: 743
v=0
o=- 0 0 IN IP4 192.168.2.11
s=session
c=IN IP4 192.168.2.11
b=CT:1000
t=0 0
m=audio 55856 RTP/AVP 97 111 112 6 0 8 4 5 3 101
k=base64:kTDEaBWz86Ak1TG1jfVz6ei1Y1H7ov8t2/brmct4Xzs
a=rtpmap:97 red/8000
a=rtpmap:111 SIREN/16000
a=fmtp:111 bitrate=16000
a=rtpmap:112 G7221/16000
a=fmtp:112 bitrate=24000
a=rtpmap:6 DVI4/16000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:5 DVI4/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=encryption:optional
m=video 17516 RTP/AVP 34 31
k=base64:dg209s9MEye/smA4gDKBIqEZQvBLyV1BzhKaPzZcIsg
a=rtpmap:34 H263/90000
a=rtpmap:31 H261/90000
a=encryption:optional
m=application 1503 tcp msdata
a=recvonly
a=encryption:optional
11(10865) tcp_send: tcp connection found (0x402e1bb0), acquiring fd
11(10865) tcp_send, c= 0x402e1bb0, n=8
19(10881) tcp_main_loop: read response= 402e1bb0, 1 from 11 (10865)
11(10865) tcp_send: after receive_fd: c= 0x402e1bb0 n=4 fd=21
11(10865) tcp_send: sending...
11(10865) tcp_send: after write: c= 0x402e1bb0 n=1286 fd=21
11(10865) tcp_send: buf=
SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.2.28:16774
From: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b
To: <sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442
Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28
CSeq: 1 INVITE
Record-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603111429;lr=on>
Contact: <sip:support at 192.168.2.253:8346;maddr=192.168.2.11;transport=tcp>
User-Agent: RTC/1.2
Content-Type: application/sdp
Content-Length: 743
v=0
o=- 0 0 IN IP4 192.168.2.11
s=session
c=IN IP4 192.168.2.11
b=CT:1000
t=0 0
m=audio 55856 RTP/AVP 97 111 112 6 0 8 4 5 3 101
k=base64:kTDEaBWz86Ak1TG1jfVz6ei1Y1H7ov8t2/brmct4Xzs
a=rtpmap:97 red/8000
a=rtpmap:111 SIREN/16000
a=fmtp:111 bitrate=16000
a=rtpmap:112 G7221/16000
a=fmtp:112 bitrate=24000
a=rtpmap:6 DVI4/16000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:5 DVI4/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=encryption:optional
m=video 17516 RTP/AVP 34 31
k=base64:dg209s9MEye/smA4gDKBIqEZQvBLyV1BzhKaPzZcIsg
a=rtpmap:34 H263/90000
a=rtpmap:31 H261/90000
a=encryption:optional
m=application 1503 tcp msdata
a=recvonly
a=encryption:optional
11(10865) DEBUG: reply relayed. buf=0x80c9868: SIP/2.0 2..., shmem=0x402f1dc0: SIP/2.0 2
11(10865) DEBUG: cleanup_uacs: RETR/FR timers reset
11(10865) DEBUG: add_to_tail_of_timer[2]: 0x40303880
11(10865) DEBUG:destroy_avp_list: destroing list (nil)
11(10865) receive_msg: cleaning up
14(10871) tcp_read_req: content-length= 0
14(10871) SIP Request:
14(10871) method: <ACK>
14(10871) uri: <sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603111429;lr=on>
14(10871) version: <SIP/2.0>
14(10871) parse_headers: flags=1
14(10871) end of header reached, state=5
14(10871) parse_headers: Via found, flags=1
14(10871) parse_headers: this is the first via
14(10871) After parse_msg...
14(10871) preparing to run routing scripts...
14(10871) DEBUG : sl_filter_ACK: to late to be a local ACK!
14(10871) DEBUG : is_maxfwd_present: searching for max_forwards header
14(10871) parse_headers: flags=128
14(10871) DEBUG: is_maxfwd_present: value = 70
14(10871) parse_headers: flags=8
14(10871) DEBUG: add_param: tag=455e2e10b10e464cafe24f3603111429
14(10871) DEBUG: add_param: epid=c83d59757b
14(10871) end of header reached, state=29
14(10871) parse_headers: flags=256
14(10871) DEBUG: add_param: tag=081ad1daf478474abd2df8a5c6b2c442
14(10871) end of header reached, state=29
14(10871) DEBUG: get_hdr_field: <To> [66]; uri=[sip:support at 192.168.2.253]
14(10871) DEBUG: to body [<sip:support at 192.168.2.253>]
14(10871) get_hdr_field: cseq <CSeq>: <1> <ACK>
14(10871) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
14(10871) check_self - checking if port 5060 matches port 5060
14(10871) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
14(10871) check_self - checking if port 5060 matches port 8346
14(10871) parse_headers: flags=256
14(10871) DEBUG: get_hdr_body : content_length=0
14(10871) found end of header
14(10871) fnr(): No next Route HF found
14(10871) ras(): No next URI found
14(10871) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
14(10871) check_self - checking if port 5060 matches port 5060
14(10871) rwrite(): Rewriting Request-URI with 'sip:192.168.2.11:7887;transport=tcp'
14(10871) ---------- PROCESSING INVITE/ACK!!!!
14(10871) parse_headers: flags=-1
14(10871) parse_headers: flags=60
14(10871) DEBUG: MD5 calculated: 1c32c26e94436c486e1911ef34f42335
14(10871) DEBUG: print_var: SIP_HF_CONTENT_LENGTH=0
14(10871) DEBUG: print_var: SIP_HF_USER_AGENT=RTC/1.2
14(10871) DEBUG: print_var: SIP_HF_ROUTE=<sip:support at 192.168.2.253:8346;maddr=192.168.2.11;transport=tcp>
14(10871) DEBUG: print_var: SIP_HF_CSEQ=1 ACK
14(10871) DEBUG: print_var: SIP_HF_CALLID=def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28
14(10871) DEBUG: print_var: SIP_HF_TO=<sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442
14(10871) DEBUG: print_var: SIP_HF_FROM="HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b
14(10871) DEBUG: print_var: SIP_HF_MAX_FORWARDS=69
14(10871) DEBUG: print_var: SIP_HF_VIA=SIP/2.0/TCP 192.168.2.28:16774
14(10871) INFO: SIGCHLD received: we do not worry about grand-children
14(10871) DEBUG: t_addifnew: msg id=6 , global msg id=5 , T on entrance=0xffffffff
14(10871) parse_headers: flags=-1
14(10871) parse_headers: flags=60
14(10871) t_lookup_request: start searching: hash=26290, isACK=1
14(10871) DEBUG: proceeding to pre-RFC3261 transaction matching
14(10871) DEBUG: t_lookup_request: e2e proxy ACK found
14(10871) SER: forwarding ACK statelessly
14(10871) DEBUG: mk_proxy: doing DNS lookup...
14(10871) build_req_from_req: id added: <;i=6>, rcv proto=2
14(10871) parse_headers: flags=2048
14(10871) check_via_address(192.168.2.28, 192.168.2.28, 0)
14(10871) Sending:
ACK sip:192.168.2.11:7887;transport=tcp SIP/2.0
Record-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603111429;lr=on>
Via: SIP/2.0/TCP 192.168.2.253;branch=0;i=6
Via: SIP/2.0/TCP 192.168.2.28:16774
Max-Forwards: 69
From: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b
To: <sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442
Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28
CSeq: 1 ACK
User-Agent: RTC/1.2
Content-Length: 0
.
14(10871) orig. len=503, new_len=527, proto=2
14(10871) tcp_send: no open tcp connection found, opening new one
14(10871) ERROR: tcp_blocking_connect: SO_ERROR (111) Connection refused
14(10871) ERROR: tcpconn_connect: tcp_blocking_connect failed
14(10871) ERROR: tcp_send: connect failed
14(10871) msg_send: ERROR: tcp_send failed
14(10871) Warning: sl_send_reply: I won't send a reply for ACK!!
14(10871) ERROR: sl_reply_error used: Unfortunately error on sending to next hop occured (477/SL)
14(10871) DEBUG:destroy_avp_list: destroing list (nil)
14(10871) receive_msg: cleaning up
14(10871) tcp_read_req: content-length= 105
14(10871) SIP Request:
14(10871) method: <INVITE>
14(10871) uri: <sip:support at 192.168.2.253>
14(10871) version: <SIP/2.0>
14(10871) parse_headers: flags=1
14(10871) end of header reached, state=5
14(10871) parse_headers: Via found, flags=1
14(10871) parse_headers: this is the first via
14(10871) After parse_msg...
14(10871) preparing to run routing scripts...
14(10871) DEBUG : is_maxfwd_present: searching for max_forwards header
14(10871) parse_headers: flags=128
14(10871) DEBUG: is_maxfwd_present: value = 70
14(10871) parse_headers: flags=8
14(10871) DEBUG: add_param: tag=e1f4c4a00746455296536eb88222dd86
14(10871) DEBUG: add_param: epid=c83d59757b
14(10871) end of header reached, state=29
14(10871) parse_headers: flags=256
14(10871) end of header reached, state=9
14(10871) DEBUG: get_hdr_field: <To> [29]; uri=[sip:support at 192.168.2.253]
14(10871) DEBUG: to body [<sip:support at 192.168.2.253>
]
14(10871) get_hdr_field: cseq <CSeq>: <1> <INVITE>
14(10871) DEBUG: get_hdr_body : content_length=105
14(10871) found end of header
14(10871) find_first_route(): No Route headers found
14(10871) loose_route(): There is no Route HF
14(10871) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
14(10871) check_self - checking if port 5060 matches port 5060
14(10871) rwrite(): Rewriting Request-URI with 'sip:192.168.2.11:7887;transport=tcp'
14(10871) ---------- PROCESSING INVITE/ACK!!!!
14(10871) parse_headers: flags=-1
14(10871) parse_headers: flags=60
14(10871) DEBUG: MD5 calculated: a246a4b391987d65562568c6c7770fe3
14(10871) DEBUG: print_var: SIP_HF_CONTENT_LENGTH=105
14(10871) DEBUG: print_var: SIP_HF_CONTENT_TYPE=application/sdp
14(10871) DEBUG: print_var: SIP_HF_SUPPORTED=com.microsoft.rtc-multiparty
14(10871) DEBUG: print_var: SIP_HF_ENDPOINTS="HS User" <sip:test2 at 192.168.2.253>, <sip:support at 192.168.2.253>
14(10871) DEBUG: print_var: SIP_HF_ROSTER_MANAGER=sip:test2 at 192.168.2.253
14(10871) DEBUG: print_var: SIP_HF_USER_AGENT=RTC/1.2
14(10871) DEBUG: print_var: SIP_HF_CONTACT=<sip:test2 at 192.168.2.253:16774;maddr=192.168.2.28;transport=tcp>
14(10871) DEBUG: print_var: SIP_HF_CSEQ=1 INVITE
14(10871) DEBUG: print_var: SIP_HF_CALLID=87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28
14(10871) DEBUG: print_var: SIP_HF_TO=<sip:support at 192.168.2.253>
14(10871) DEBUG: print_var: SIP_HF_FROM="HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b
14(10871) DEBUG: print_var: SIP_HF_MAX_FORWARDS=69
14(10871) DEBUG: print_var: SIP_HF_VIA=SIP/2.0/TCP 192.168.2.28:16774
14(10871) INFO: SIGCHLD received: we do not worry about grand-children
14(10871) DEBUG: t_addifnew: msg id=7 , global msg id=6 , T on entrance=(nil)
14(10871) parse_headers: flags=-1
14(10871) parse_headers: flags=60
14(10871) t_lookup_request: start searching: hash=49009, isACK=0
14(10871) DEBUG: proceeding to pre-RFC3261 transaction matching
14(10871) DEBUG: t_lookup_request: no transaction found
14(10871) SER: new INVITE
14(10871) parse_headers: flags=-1
14(10871) check_via_address(192.168.2.28, 192.168.2.28, 0)
14(10871) WARNING:vqm_resize: resize(0) called
14(10871) tcp_send: tcp connection found (0x402e1bb0), acquiring fd
14(10871) tcp_send, c= 0x402e1bb0, n=8
19(10881) tcp_main_loop: read response= 402e1bb0, 1 from 14 (10871)
14(10871) tcp_send: after receive_fd: c= 0x402e1bb0 n=4 fd=27
14(10871) tcp_send: sending...
14(10871) tcp_send: after write: c= 0x402e1bb0 n=559 fd=27
14(10871) tcp_send: buf=
SIP/2.0 100 trying -- your call is important to us
Via: SIP/2.0/TCP 192.168.2.28:16774
From: "HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b
To: <sip:support at 192.168.2.253>
Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28
CSeq: 1 INVITE
Server: Sip EXpress router (0.8.14 (i386/linux))
Content-Length: 0
Warning: 392 192.168.2.253:5060 "Noisy feedback tells: pid=10871 req_src_ip=192.168.2.28 req_src_port=1071 in_uri=sip:support at 192.168.2.253 out_uri=sip:192.168.2.11:7887;transport=tcp via_cnt==1"
14(10871) DEBUG: reply sent out. buf=0x80c8fd0: SIP/2.0 1..., shmem=0x402f2ed8: SIP/2.0 1
14(10871) DEBUG: t_reply: finished
14(10871) DEBUG: mk_proxy: doing DNS lookup...
14(10871) build_req_from_req: id added: <;i=6>, rcv proto=2
14(10871) parse_headers: flags=2048
14(10871) check_via_address(192.168.2.28, 192.168.2.28, 0)
14(10871) DEBUG: mk_proxy: doing DNS lookup...
14(10871) build_req_from_req: id added: <;i=6>, rcv proto=2
14(10871) parse_headers: flags=2048
14(10871) check_via_address(192.168.2.28, 192.168.2.28, 0)
14(10871) tcp_send: no open tcp connection found, opening new one
14(10871) ERROR: tcp_blocking_connect: SO_ERROR (111) Connection refused
14(10871) ERROR: tcpconn_connect: tcp_blocking_connect failed
14(10871) ERROR: tcp_send: connect failed
14(10871) msg_send: ERROR: tcp_send failed
14(10871) ERROR: t_forward_nonack: sending request failed
14(10871) DEBUG: add_to_tail_of_timer[0]: 0x402f442c
14(10871) tcp_send: tcp connection found (0x403051f0), acquiring fd
19(10881) tcp_main_loop: read response= 403051f0, 1 from 14 (10871)
14(10871) tcp_send, c= 0x403051f0, n=8
14(10871) tcp_send: after receive_fd: c= 0x403051f0 n=4 fd=27
14(10871) tcp_send: sending...
14(10871) tcp_send: after write: c= 0x403051f0 n=889 fd=27
14(10871) tcp_send: buf=
INVITE sip:192.168.2.11:8346;transport=tcp SIP/2.0
Record-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=e1f4c4a00746455296536eb88222dd86;lr=on>
Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK17fb.48e182d5.1;i=6
Via: SIP/2.0/TCP 192.168.2.28:16774
Max-Forwards: 69
From: "HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b
To: <sip:support at 192.168.2.253>
Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28
CSeq: 1 INVITE
Contact: <sip:test2 at 192.168.2.253:16774;maddr=192.168.2.28;transport=tcp>
User-Agent: RTC/1.2
Roster-Manager: sip:test2 at 192.168.2.253
EndPoints: "HS User" <sip:test2 at 192.168.2.253>, <sip:support at 192.168.2.253>
Supported: com.microsoft.rtc-multiparty
Content-Type: application/sdp
Content-Length: 105
v=0
o=- 0 0 IN IP4 192.168.2.28
s=session
c=IN IP4 192.168.2.28
t=0 0
m=x-ms-message 5060 sip null
14(10871) DEBUG: add_to_tail_of_timer[0]: 0x402f450c
14(10871) SER: new transaction fwd'ed
14(10871) DEBUG:destroy_avp_list: destroing list (nil)
14(10871) receive_msg: cleaning up
10(10863) DEBUG: timer routine:0,tl=0x40303974 next=(nil)
10(10863) DEBUG: timer routine:1,tl=0x40303a54 next=(nil)
11(10865) tcp_read_req: content-length= 0
11(10865) SIP Reply (status):
11(10865) version: <SIP/2.0>
11(10865) status: <100>
11(10865) reason: <Trying>
11(10865) parse_headers: flags=1
11(10865) Found param type 232, <branch> = <z9hG4bK17fb.48e182d5.1>; state=611(10865) Found param type 236, <i> = <6>; state=16
11(10865) end of header reached, state=5
11(10865) parse_headers: Via found, flags=1
11(10865) parse_headers: this is the first via
11(10865) After parse_msg...
11(10865) forward_reply: found module tm, passing reply to it
11(10865) DEBUG: t_check: msg id=14 global id=13 T start=0xffffffff
11(10865) parse_headers: flags=17
11(10865) end of header reached, state=5
11(10865) parse_headers: Via found, flags=17
11(10865) parse_headers: this is the second via
11(10865) DEBUG: add_param: tag=001effba0777449e996aafc0321dd600
11(10865) end of header reached, state=29
11(10865) DEBUG: get_hdr_field: <To> [66]; uri=[sip:support at 192.168.2.253]
11(10865) DEBUG: to body [<sip:support at 192.168.2.253>]
11(10865) get_hdr_field: cseq <CSeq>: <1> <INVITE>
11(10865) parse_headers: flags=4
11(10865) DEBUG: t_reply_matching: hash 49009 label 1562910340 branch 1
11(10865) DEBUG: t_reply_matching: reply matched (T=0x402f42f0)!
11(10865) DEBUG: t_check: msg id=14 global id=14 T end=0x402f42f0
11(10865) DEBUG: t_on_reply: org. status uas=100, uac[1]=0 local=0 is_invite=1)
11(10865) ->>>>>>>>> T_code=100, new_code=100
11(10865) DEBUG: relay_reply: branch=1, save=0, relay=-1
11(10865) DEBUG: add_to_tail_of_timer[1]: 0x402f450c
11(10865) DEBUG:destroy_avp_list: destroing list (nil)
11(10865) receive_msg: cleaning up
11(10865) tcp_read_req: content-length= 105
11(10865) SIP Reply (status):
11(10865) version: <SIP/2.0>
11(10865) status: <200>
11(10865) reason: <OK>
11(10865) parse_headers: flags=1
11(10865) Found param type 232, <branch> = <z9hG4bK17fb.48e182d5.1>; state=611(10865) Found param type 236, <i> = <6>; state=16
11(10865) end of header reached, state=5
11(10865) parse_headers: Via found, flags=1
11(10865) parse_headers: this is the first via
11(10865) After parse_msg...
11(10865) forward_reply: found module tm, passing reply to it
11(10865) DEBUG: t_check: msg id=15 global id=14 T start=0xffffffff
11(10865) parse_headers: flags=17
11(10865) end of header reached, state=5
11(10865) parse_headers: Via found, flags=17
11(10865) parse_headers: this is the second via
11(10865) DEBUG: add_param: tag=001effba0777449e996aafc0321dd600
11(10865) end of header reached, state=29
11(10865) DEBUG: get_hdr_field: <To> [66]; uri=[sip:support at 192.168.2.253]
11(10865) DEBUG: to body [<sip:support at 192.168.2.253>]
11(10865) get_hdr_field: cseq <CSeq>: <1> <INVITE>
11(10865) parse_headers: flags=4
11(10865) DEBUG: t_reply_matching: hash 49009 label 1562910340 branch 1
11(10865) DEBUG: t_reply_matching: reply matched (T=0x402f42f0)!
11(10865) DEBUG: t_check: msg id=15 global id=15 T end=0x402f42f0
11(10865) DEBUG: t_on_reply: org. status uas=100, uac[1]=100 local=0 is_invite=1)
11(10865) ->>>>>>>>> T_code=100, new_code=200
11(10865) DEBUG: relay_reply: branch=1, save=0, relay=1
11(10865) parse_headers: flags=2048
11(10865) DEBUG: get_hdr_body : content_length=105
11(10865) old size: 755, new size: 689
11(10865) build_res_from_sip_res: copied size: orig:82, new: 16, rest: 673 msg=
SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.2.28:16774
From: "HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b
To: <sip:support at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600
Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28
CSeq: 1 INVITE
Record-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=e1f4c4a00746455296536eb88222dd86;lr=on>
Contact: <sip:support at 192.168.2.253:8346;maddr=192.168.2.11;transport=tcp>
User-Agent: RTC/1.2
Supported: com.microsoft.rtc-multiparty
Content-Type: application/sdp
Content-Length: 105
v=0
o=- 0 0 IN IP4 192.168.2.11
s=session
c=IN IP4 192.168.2.11
t=0 0
m=x-ms-message 5060 sip null
11(10865) tcp_send: tcp connection found (0x402e1bb0), acquiring fd
11(10865) tcp_send, c= 0x402e1bb0, n=8
19(10881) tcp_main_loop: read response= 402e1bb0, 1 from 11 (10865)
11(10865) tcp_send: after receive_fd: c= 0x402e1bb0 n=4 fd=21
11(10865) tcp_send: sending...
11(10865) tcp_send: after write: c= 0x402e1bb0 n=689 fd=21
11(10865) tcp_send: buf=
SIP/2.0 200 OK
Via: SIP/2.0/TCP 192.168.2.28:16774
From: "HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b
To: <sip:support at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600
Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28
CSeq: 1 INVITE
Record-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=e1f4c4a00746455296536eb88222dd86;lr=on>
Contact: <sip:support at 192.168.2.253:8346;maddr=192.168.2.11;transport=tcp>
User-Agent: RTC/1.2
Supported: com.microsoft.rtc-multiparty
Content-Type: application/sdp
Content-Length: 105
v=0
o=- 0 0 IN IP4 192.168.2.11
s=session
c=IN IP4 192.168.2.11
t=0 0
m=x-ms-message 5060 sip null
11(10865) DEBUG: reply relayed. buf=0x80c9868: SIP/2.0 2..., shmem=0x402f2ed8: SIP/2.0 2
11(10865) DEBUG: cleanup_uacs: RETR/FR timers reset
11(10865) DEBUG: add_to_tail_of_timer[2]: 0x402f4338
11(10865) DEBUG:destroy_avp_list: destroing list (nil)
11(10865) receive_msg: cleaning up
14(10871) tcp_read_req: content-length= 0
14(10871) SIP Request:
14(10871) method: <ACK>
14(10871) uri: <sip:support at 192.168.2.253;transport=tcp;ftag=e1f4c4a00746455296536eb88222dd86;lr=on>
14(10871) version: <SIP/2.0>
14(10871) parse_headers: flags=1
14(10871) end of header reached, state=5
14(10871) parse_headers: Via found, flags=1
14(10871) parse_headers: this is the first via
14(10871) After parse_msg...
14(10871) preparing to run routing scripts...
14(10871) DEBUG : sl_filter_ACK: to late to be a local ACK!
14(10871) DEBUG : is_maxfwd_present: searching for max_forwards header
14(10871) parse_headers: flags=128
14(10871) DEBUG: is_maxfwd_present: value = 70
14(10871) parse_headers: flags=8
14(10871) DEBUG: add_param: tag=e1f4c4a00746455296536eb88222dd86
14(10871) DEBUG: add_param: epid=c83d59757b
14(10871) end of header reached, state=29
14(10871) parse_headers: flags=256
14(10871) DEBUG: add_param: tag=001effba0777449e996aafc0321dd600
14(10871) end of header reached, state=29
14(10871) DEBUG: get_hdr_field: <To> [66]; uri=[sip:support at 192.168.2.253]
14(10871) DEBUG: to body [<sip:support at 192.168.2.253>]
14(10871) get_hdr_field: cseq <CSeq>: <1> <ACK>
14(10871) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
14(10871) check_self - checking if port 5060 matches port 5060
14(10871) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
14(10871) check_self - checking if port 5060 matches port 8346
14(10871) parse_headers: flags=256
14(10871) DEBUG: get_hdr_body : content_length=0
14(10871) found end of header
14(10871) fnr(): No next Route HF found
14(10871) ras(): No next URI found
14(10871) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
14(10871) check_self - checking if port 5060 matches port 5060
14(10871) rwrite(): Rewriting Request-URI with 'sip:192.168.2.11:7887;transport=tcp'
14(10871) ---------- PROCESSING INVITE/ACK!!!!
14(10871) parse_headers: flags=-1
14(10871) parse_headers: flags=60
14(10871) DEBUG: MD5 calculated: bedced1df157cbcfda027e06fd396f17
14(10871) DEBUG: print_var: SIP_HF_CONTENT_LENGTH=0
14(10871) DEBUG: print_var: SIP_HF_USER_AGENT=RTC/1.2
14(10871) DEBUG: print_var: SIP_HF_ROUTE=<sip:support at 192.168.2.253:8346;maddr=192.168.2.11;transport=tcp>
14(10871) DEBUG: print_var: SIP_HF_CSEQ=1 ACK
14(10871) DEBUG: print_var: SIP_HF_CALLID=87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28
14(10871) DEBUG: print_var: SIP_HF_TO=<sip:support at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600
14(10871) DEBUG: print_var: SIP_HF_FROM="HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b
14(10871) DEBUG: print_var: SIP_HF_MAX_FORWARDS=69
14(10871) DEBUG: print_var: SIP_HF_VIA=SIP/2.0/TCP 192.168.2.28:16774
14(10871) INFO: SIGCHLD received: we do not worry about grand-children
14(10871) DEBUG: t_addifnew: msg id=8 , global msg id=7 , T on entrance=0xffffffff
14(10871) parse_headers: flags=-1
14(10871) parse_headers: flags=60
14(10871) t_lookup_request: start searching: hash=49009, isACK=1
14(10871) DEBUG: proceeding to pre-RFC3261 transaction matching
14(10871) DEBUG: t_lookup_request: e2e proxy ACK found
14(10871) SER: forwarding ACK statelessly
14(10871) DEBUG: mk_proxy: doing DNS lookup...
14(10871) build_req_from_req: id added: <;i=6>, rcv proto=2
14(10871) parse_headers: flags=2048
14(10871) check_via_address(192.168.2.28, 192.168.2.28, 0)
14(10871) Sending:
ACK sip:192.168.2.11:7887;transport=tcp SIP/2.0
Record-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=e1f4c4a00746455296536eb88222dd86;lr=on>
Via: SIP/2.0/TCP 192.168.2.253;branch=0;i=6
Via: SIP/2.0/TCP 192.168.2.28:16774
Max-Forwards: 69
From: "HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b
To: <sip:support at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600
Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28
CSeq: 1 ACK
User-Agent: RTC/1.2
Content-Length: 0
.
14(10871) orig. len=503, new_len=527, proto=2
14(10871) tcp_send: no open tcp connection found, opening new one
14(10871) ERROR: tcp_blocking_connect: SO_ERROR (111) Connection refused
14(10871) ERROR: tcpconn_connect: tcp_blocking_connect failed
14(10871) ERROR: tcp_send: connect failed
14(10871) msg_send: ERROR: tcp_send failed
14(10871) Warning: sl_send_reply: I won't send a reply for ACK!!
14(10871) ERROR: sl_reply_error used: Unfortunately error on sending to next hop occured (477/SL)
14(10871) DEBUG:destroy_avp_list: destroing list (nil)
14(10871) receive_msg: cleaning up
10(10863) DEBUG: timer routine:0,tl=0x402f442c next=(nil)
10(10863) DEBUG: timer routine:1,tl=0x402f450c next=(nil)
10(10863) DEBUG: timer routine:2,tl=0x40303880 next=(nil)
10(10863) DEBUG: wait_handler : removing 0x40303838 from table
10(10863) DEBUG: delete transaction 0x40303838
10(10863) DEBUG: wait_handler : done
10(10863) DEBUG: timer routine:2,tl=0x402f4338 next=(nil)
10(10863) DEBUG: wait_handler : removing 0x402f42f0 from table
10(10863) DEBUG: delete transaction 0x402f42f0
10(10863) DEBUG: wait_handler : done
11(10865) tcp_receive_loop: 0x403051f0 expired (115, 116)
11(10865) releasing con 0x403051f0, state 0, fd=20, id=10
11(10865) extra_data (nil)
19(10881) tcp_main_loop: reader response= 403051f0, 0 from 0
19(10881) tcp_main_loop: CONN_RELEASE 0x403051f0 refcnt= 0
14(10871) tcp_receive_loop: 0x402e1bb0 expired (115, 116)
14(10871) releasing con 0x402e1bb0, state 0, fd=26, id=6
14(10871) extra_data (nil)
19(10881) tcp_main_loop: reader response= 402e1bb0, 0 from 3
19(10881) tcp_main_loop: CONN_RELEASE 0x402e1bb0 refcnt= 0
19(10881) tcp_main_loop: data available on 0x402d19a0 [h:183] 35
19(10881) send2child: to tcp child 0 11(10865), 0x402d19a0
11(10865) received n=4 con=0x402d19a0, fd=20
11(10865) tcp_read_req: content-length= 0
11(10865) SIP Request:
11(10865) method: <BYE>
11(10865) uri: <sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603111429;lr=on>
11(10865) version: <SIP/2.0>
11(10865) parse_headers: flags=1
11(10865) end of header reached, state=5
11(10865) parse_headers: Via found, flags=1
11(10865) parse_headers: this is the first via
11(10865) After parse_msg...
11(10865) preparing to run routing scripts...
11(10865) DEBUG : is_maxfwd_present: searching for max_forwards header
11(10865) parse_headers: flags=128
11(10865) DEBUG: is_maxfwd_present: value = 70
11(10865) parse_headers: flags=8
11(10865) DEBUG: add_param: tag=081ad1daf478474abd2df8a5c6b2c442
11(10865) end of header reached, state=29
11(10865) parse_headers: flags=256
11(10865) DEBUG: add_param: tag=455e2e10b10e464cafe24f3603111429
11(10865) DEBUG: add_param: epid=c83d59757b
11(10865) end of header reached, state=29
11(10865) DEBUG: get_hdr_field: <To> [90]; uri=[sip:test2 at 192.168.2.253]
11(10865) DEBUG: to body ["HS User" <sip:test2 at 192.168.2.253>]
11(10865) get_hdr_field: cseq <CSeq>: <1> <BYE>
11(10865) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
11(10865) check_self - checking if port 5060 matches port 5060
11(10865) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
11(10865) check_self - checking if port 5060 matches port 16774
11(10865) parse_headers: flags=256
11(10865) DEBUG: get_hdr_body : content_length=0
11(10865) found end of header
11(10865) fnr(): No next Route HF found
11(10865) ras(): No next URI found
11(10865) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
11(10865) check_self - checking if port 5060 matches port 5060
11(10865) rwrite(): Rewriting Request-URI with 'sip:192.168.2.11:7887;transport=tcp'
11(10865) ---------- PROCESSING BYE!!!!
11(10865) parse_headers: flags=-1
11(10865) parse_headers: flags=60
11(10865) DEBUG: MD5 calculated: b6e9a98fb571469ffdf45fe8b871d9c4
11(10865) DEBUG: print_var: SIP_HF_CONTENT_LENGTH=0
11(10865) DEBUG: print_var: SIP_HF_USER_AGENT=RTC/1.2
11(10865) DEBUG: print_var: SIP_HF_ROUTE=<sip:test2 at 192.168.2.253:16774;maddr=192.168.2.28;transport=tcp>
11(10865) DEBUG: print_var: SIP_HF_CSEQ=1 BYE
11(10865) DEBUG: print_var: SIP_HF_CALLID=def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28
11(10865) DEBUG: print_var: SIP_HF_TO="HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b
11(10865) DEBUG: print_var: SIP_HF_FROM=<sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442
11(10865) DEBUG: print_var: SIP_HF_MAX_FORWARDS=69
11(10865) DEBUG: print_var: SIP_HF_VIA=SIP/2.0/TCP 192.168.2.11:8346
11(10865) INFO: SIGCHLD received: we do not worry about grand-children
11(10865) DEBUG: t_addifnew: msg id=16 , global msg id=15 , T on entrance=0xffffffff
11(10865) parse_headers: flags=-1
11(10865) parse_headers: flags=60
11(10865) t_lookup_request: start searching: hash=26290, isACK=0
11(10865) DEBUG: proceeding to pre-RFC3261 transaction matching
11(10865) DEBUG: t_lookup_request: no transaction found
11(10865) DEBUG: mk_proxy: doing DNS lookup...
11(10865) build_req_from_req: id added: <;i=9>, rcv proto=2
11(10865) parse_headers: flags=2048
11(10865) check_via_address(192.168.2.11, 192.168.2.11, 0)
11(10865) DEBUG: mk_proxy: doing DNS lookup...
11(10865) build_req_from_req: id added: <;i=9>, rcv proto=2
11(10865) parse_headers: flags=2048
11(10865) check_via_address(192.168.2.11, 192.168.2.11, 0)
11(10865) tcp_send: no open tcp connection found, opening new one
11(10865) ERROR: tcp_blocking_connect: SO_ERROR (111) Connection refused
11(10865) ERROR: tcpconn_connect: tcp_blocking_connect failed
11(10865) ERROR: tcp_send: connect failed
11(10865) msg_send: ERROR: tcp_send failed
11(10865) ERROR: t_forward_nonack: sending request failed
11(10865) DEBUG: add_to_tail_of_timer[0]: 0x402f442c
11(10865) tcp_send: tcp connection found (0x403051f0), acquiring fd
19(10881) tcp_main_loop: read response= 403051f0, 1 from 11 (10865)
11(10865) tcp_send, c= 0x403051f0, n=8
11(10865) tcp_send: after receive_fd: c= 0x403051f0 n=4 fd=21
11(10865) tcp_send: sending...
11(10865) tcp_send: after write: c= 0x403051f0 n=547 fd=21
11(10865) tcp_send: buf=
BYE sip:192.168.2.11:8346;transport=tcp SIP/2.0
Record-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=081ad1daf478474abd2df8a5c6b2c442;lr=on>
Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK2b66.78f50de5.1;i=9
Via: SIP/2.0/TCP 192.168.2.11:8346
Max-Forwards: 69
From: <sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442
To: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b
Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28
CSeq: 1 BYE
User-Agent: RTC/1.2
Content-Length: 0
11(10865) DEBUG: add_to_tail_of_timer[0]: 0x402f450c
11(10865) SER: new transaction fwd'ed
11(10865) DEBUG:destroy_avp_list: destroing list (nil)
11(10865) receive_msg: cleaning up
11(10865) tcp_read_req: content-length= 0
11(10865) SIP Request:
11(10865) method: <BYE>
11(10865) uri: <sip:support at 192.168.2.253;transport=tcp;ftag=e1f4c4a00746455296536eb88222dd86;lr=on>
11(10865) version: <SIP/2.0>
11(10865) parse_headers: flags=1
11(10865) end of header reached, state=5
11(10865) parse_headers: Via found, flags=1
11(10865) parse_headers: this is the first via
11(10865) After parse_msg...
11(10865) preparing to run routing scripts...
11(10865) DEBUG : is_maxfwd_present: searching for max_forwards header
11(10865) parse_headers: flags=128
11(10865) DEBUG: is_maxfwd_present: value = 70
11(10865) parse_headers: flags=8
11(10865) DEBUG: add_param: tag=001effba0777449e996aafc0321dd600
11(10865) end of header reached, state=29
11(10865) parse_headers: flags=256
11(10865) DEBUG: add_param: tag=e1f4c4a00746455296536eb88222dd86
11(10865) DEBUG: add_param: epid=c83d59757b
11(10865) end of header reached, state=29
11(10865) DEBUG: get_hdr_field: <To> [90]; uri=[sip:test2 at 192.168.2.253]
11(10865) DEBUG: to body ["HS User" <sip:test2 at 192.168.2.253>]
11(10865) get_hdr_field: cseq <CSeq>: <1> <BYE>
11(10865) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
11(10865) check_self - checking if port 5060 matches port 5060
11(10865) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
11(10865) check_self - checking if port 5060 matches port 16774
11(10865) parse_headers: flags=256
11(10865) DEBUG: get_hdr_body : content_length=0
11(10865) found end of header
11(10865) fnr(): No next Route HF found
11(10865) ras(): No next URI found
11(10865) check_self - checking if host==us: 13==13 && [192.168.2.253] == [192.168.2.253]
11(10865) check_self - checking if port 5060 matches port 5060
11(10865) rwrite(): Rewriting Request-URI with 'sip:192.168.2.11:7887;transport=tcp'
11(10865) ---------- PROCESSING BYE!!!!
11(10865) parse_headers: flags=-1
11(10865) parse_headers: flags=60
11(10865) DEBUG: MD5 calculated: f1a418a1cba5543f4d2ed7f6e7559e52
11(10865) DEBUG: print_var: SIP_HF_CONTENT_LENGTH=0
11(10865) DEBUG: print_var: SIP_HF_USER_AGENT=RTC/1.2
11(10865) DEBUG: print_var: SIP_HF_ROUTE=<sip:test2 at 192.168.2.253:16774;maddr=192.168.2.28;transport=tcp>
11(10865) DEBUG: print_var: SIP_HF_CSEQ=1 BYE
11(10865) DEBUG: print_var: SIP_HF_CALLID=87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28
11(10865) DEBUG: print_var: SIP_HF_TO="HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b
11(10865) DEBUG: print_var: SIP_HF_FROM=<sip:support at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600
11(10865) DEBUG: print_var: SIP_HF_MAX_FORWARDS=69
11(10865) DEBUG: print_var: SIP_HF_VIA=SIP/2.0/TCP 192.168.2.11:8346
11(10865) INFO: SIGCHLD received: we do not worry about grand-children
11(10865) DEBUG: t_addifnew: msg id=17 , global msg id=16 , T on entrance=0xffffffff
11(10865) parse_headers: flags=-1
11(10865) parse_headers: flags=60
11(10865) t_lookup_request: start searching: hash=49009, isACK=0
11(10865) DEBUG: proceeding to pre-RFC3261 transaction matching
11(10865) DEBUG: t_lookup_request: no transaction found
11(10865) DEBUG: mk_proxy: doing DNS lookup...
11(10865) build_req_from_req: id added: <;i=9>, rcv proto=2
11(10865) parse_headers: flags=2048
11(10865) check_via_address(192.168.2.11, 192.168.2.11, 0)
11(10865) DEBUG: mk_proxy: doing DNS lookup...
11(10865) build_req_from_req: id added: <;i=9>, rcv proto=2
11(10865) parse_headers: flags=2048
11(10865) check_via_address(192.168.2.11, 192.168.2.11, 0)
11(10865) tcp_send: no open tcp connection found, opening new one
11(10865) ERROR: tcp_blocking_connect: SO_ERROR (111) Connection refused
11(10865) ERROR: tcpconn_connect: tcp_blocking_connect failed
11(10865) ERROR: tcp_send: connect failed
11(10865) msg_send: ERROR: tcp_send failed
11(10865) ERROR: t_forward_nonack: sending request failed
11(10865) DEBUG: add_to_tail_of_timer[0]: 0x40303974
11(10865) tcp_send: tcp connection found (0x403051f0), acquiring fd
19(10881) tcp_main_loop: read response= 403051f0, 1 from 11 (10865)
11(10865) tcp_send, c= 0x403051f0, n=8
11(10865) tcp_send: after receive_fd: c= 0x403051f0 n=4 fd=21
11(10865) tcp_send: sending...
11(10865) tcp_send: after write: c= 0x403051f0 n=547 fd=21
11(10865) tcp_send: buf=
BYE sip:192.168.2.11:8346;transport=tcp SIP/2.0
Record-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=001effba0777449e996aafc0321dd600;lr=on>
Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK17fb.58e182d5.1;i=9
Via: SIP/2.0/TCP 192.168.2.11:8346
Max-Forwards: 69
From: <sip:support at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600
To: "HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b
Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28
CSeq: 1 BYE
User-Agent: RTC/1.2
Content-Length: 0
11(10865) DEBUG: add_to_tail_of_timer[0]: 0x40303a54
11(10865) SER: new transaction fwd'ed
11(10865) DEBUG:destroy_avp_list: destroing list (nil)
11(10865) receive_msg: cleaning up
19(10881) tcp_main_loop: data available on 0x403051f0 [h:90] 33
19(10881) send2child: to tcp child 3 14(10871), 0x403051f0
14(10871) received n=4 con=0x403051f0, fd=26
14(10871) tcp_read_req: content-length= 0
14(10871) SIP Reply (status):
14(10871) version: <SIP/2.0>
14(10871) status: <481>
14(10871) reason: <Call Leg/Transaction Does Not Exist>
14(10871) parse_headers: flags=1
14(10871) Found param type 232, <branch> = <z9hG4bK2b66.78f50de5.1>; state=614(10871) Found param type 236, <i> = <9>; state=16
14(10871) end of header reached, state=5
14(10871) parse_headers: Via found, flags=1
14(10871) parse_headers: this is the first via
14(10871) After parse_msg...
14(10871) forward_reply: found module tm, passing reply to it
14(10871) DEBUG: t_check: msg id=9 global id=8 T start=(nil)
14(10871) parse_headers: flags=17
14(10871) end of header reached, state=5
14(10871) parse_headers: Via found, flags=17
14(10871) parse_headers: this is the second via
14(10871) DEBUG: add_param: tag=455e2e10b10e464cafe24f3603111429
14(10871) DEBUG: add_param: epid=c83d59757b
14(10871) end of header reached, state=29
14(10871) DEBUG: get_hdr_field: <To> [90]; uri=[sip:test2 at 192.168.2.253]
14(10871) DEBUG: to body ["HS User" <sip:test2 at 192.168.2.253>]
14(10871) get_hdr_field: cseq <CSeq>: <1> <BYE>
14(10871) DEBUG: t_reply_matching: hash 26290 label 1590714247 branch 1
14(10871) DEBUG: t_reply_matching: reply matched (T=0x402f42f0)!
14(10871) DEBUG: t_check: msg id=9 global id=9 T end=0x402f42f0
14(10871) DEBUG: t_on_reply: org. status uas=0, uac[1]=0 local=0 is_invite=0)
14(10871) ->>>>>>>>> T_code=0, new_code=481
14(10871) DEBUG: relay_reply: branch=1, save=1, relay=-1
14(10871) DEBUG:destroy_avp_list: destroing list (nil)
14(10871) receive_msg: cleaning up
14(10871) tcp_read_req: content-length= 0
14(10871) SIP Reply (status):
14(10871) version: <SIP/2.0>
14(10871) status: <481>
14(10871) reason: <Call Leg/Transaction Does Not Exist>
14(10871) parse_headers: flags=1
14(10871) Found param type 232, <branch> = <z9hG4bK17fb.58e182d5.1>; state=614(10871) Found param type 236, <i> = <9>; state=16
14(10871) end of header reached, state=5
14(10871) parse_headers: Via found, flags=1
14(10871) parse_headers: this is the first via
14(10871) After parse_msg...
14(10871) forward_reply: found module tm, passing reply to it
14(10871) DEBUG: t_check: msg id=10 global id=9 T start=0xffffffff
14(10871) parse_headers: flags=17
14(10871) end of header reached, state=5
14(10871) parse_headers: Via found, flags=17
14(10871) parse_headers: this is the second via
14(10871) DEBUG: add_param: tag=e1f4c4a00746455296536eb88222dd86
14(10871) DEBUG: add_param: epid=c83d59757b
14(10871) end of header reached, state=29
14(10871) DEBUG: get_hdr_field: <To> [90]; uri=[sip:test2 at 192.168.2.253]
14(10871) DEBUG: to body ["HS User" <sip:test2 at 192.168.2.253>]
14(10871) get_hdr_field: cseq <CSeq>: <1> <BYE>
14(10871) DEBUG: t_reply_matching: hash 49009 label 1562910341 branch 1
14(10871) DEBUG: t_reply_matching: reply matched (T=0x40303838)!
14(10871) DEBUG: t_check: msg id=10 global id=10 T end=0x40303838
14(10871) DEBUG: t_on_reply: org. status uas=0, uac[1]=0 local=0 is_invite=0)
14(10871) ->>>>>>>>> T_code=0, new_code=481
14(10871) DEBUG: relay_reply: branch=1, save=1, relay=-1
14(10871) DEBUG:destroy_avp_list: destroing list (nil)
14(10871) receive_msg: cleaning up
11(10865) tcp_receive_loop: 0x402d19a0 expired (146, 147)
11(10865) releasing con 0x402d19a0, state 0, fd=20, id=9
11(10865) extra_data (nil)
19(10881) tcp_main_loop: reader response= 402d19a0, 0 from 0
19(10881) tcp_main_loop: CONN_RELEASE 0x402d19a0 refcnt= 0
14(10871) tcp_receive_loop: 0x403051f0 expired (147, 148)
14(10871) releasing con 0x403051f0, state 0, fd=26, id=10
14(10871) extra_data (nil)
19(10881) tcp_main_loop: reader response= 403051f0, 0 from 3
19(10881) tcp_main_loop: CONN_RELEASE 0x403051f0 refcnt= 0
10(10863) DEBUG: timer routine:0,tl=0x402f442c next=0x402f450c
10(10863) DEBUG: FR_handler:stop retr. and send CANCEL (0x402f42f0)
10(10863) ->>>>>>>>> T_code=0, new_code=408
10(10863) DEBUG: relay_reply: branch=0, save=0, relay=0
10(10863) parse_headers: flags=-1
10(10863) check_via_address(192.168.2.11, 192.168.2.11, 0)
10(10863) WARNING:vqm_resize: resize(0) called
10(10863) tcp_send: tcp connection found (0x402d19a0), acquiring fd
10(10863) tcp_send, c= 0x402d19a0, n=8
19(10881) tcp_main_loop: read response= 402d19a0, 1 from 10 (10863)
10(10863) tcp_send: after receive_fd: c= 0x402d19a0 n=4 fd=17
10(10863) tcp_send: sending...
10(10863) tcp_send: after write: c= 0x402d19a0 n=627 fd=17
10(10863) tcp_send: buf=
SIP/2.0 408 Request Timeout
Via: SIP/2.0/TCP 192.168.2.11:8346
From: <sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442
To: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b
Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28
CSeq: 1 BYE
Server: Sip EXpress router (0.8.14 (i386/linux))
Content-Length: 0
Warning: 392 192.168.2.253:5060 "Noisy feedback tells: pid=10863 req_src_ip=192.168.2.11 req_src_port=1143 in_uri=sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603111429;lr=on out_uri=sip:192.168.2.11:7887;transport=tcp via_cnt==0"
10(10863) DEBUG: reply relayed. buf=0x80c7a58: SIP/2.0 4..., shmem=0x402f2ed8: SIP/2.0 4
10(10863) DEBUG: add_to_tail_of_timer[2]: 0x402f4338
10(10863) DEBUG: final_response_handler : done
10(10863) DEBUG: timer routine:0,tl=0x402f450c next=0x40303974
10(10863) DEBUG: timer routine:0,tl=0x40303974 next=0x40303a54
10(10863) DEBUG: FR_handler:stop retr. and send CANCEL (0x40303838)
10(10863) ->>>>>>>>> T_code=0, new_code=408
10(10863) DEBUG: relay_reply: branch=0, save=0, relay=0
10(10863) parse_headers: flags=-1
10(10863) check_via_address(192.168.2.11, 192.168.2.11, 0)
10(10863) WARNING:vqm_resize: resize(0) called
10(10863) tcp_send: tcp connection found (0x402d19a0), acquiring fd
10(10863) tcp_send, c= 0x402d19a0, n=8
19(10881) tcp_main_loop: read response= 402d19a0, 1 from 10 (10863)
10(10863) tcp_send: after receive_fd: c= 0x402d19a0 n=4 fd=17
10(10863) tcp_send: sending...
10(10863) tcp_send: after write: c= 0x402d19a0 n=627 fd=17
10(10863) tcp_send: buf=
SIP/2.0 408 Request Timeout
Via: SIP/2.0/TCP 192.168.2.11:8346
From: <sip:support at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600
To: "HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b
Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28
CSeq: 1 BYE
Server: Sip EXpress router (0.8.14 (i386/linux))
Content-Length: 0
Warning: 392 192.168.2.253:5060 "Noisy feedback tells: pid=10863 req_src_ip=192.168.2.11 req_src_port=1143 in_uri=sip:support at 192.168.2.253;transport=tcp;ftag=e1f4c4a00746455296536eb88222dd86;lr=on out_uri=sip:192.168.2.11:7887;transport=tcp via_cnt==0"
10(10863) DEBUG: reply relayed. buf=0x80c7a58: SIP/2.0 4..., shmem=0x402f2658: SIP/2.0 4
10(10863) DEBUG: add_to_tail_of_timer[2]: 0x40303880
10(10863) DEBUG: final_response_handler : done
10(10863) DEBUG: timer routine:0,tl=0x40303a54 next=(nil)
10(10863) DEBUG: timer routine:2,tl=0x402f4338 next=0x40303880
10(10863) DEBUG: wait_handler : removing 0x402f42f0 from table
10(10863) DEBUG: delete transaction 0x402f42f0
10(10863) DEBUG: wait_handler : done
10(10863) DEBUG: timer routine:2,tl=0x40303880 next=(nil)
10(10863) DEBUG: wait_handler : removing 0x40303838 from table
10(10863) DEBUG: delete transaction 0x40303838
10(10863) DEBUG: wait_handler : done
T 192.168.2.28:1071 -> 192.168.2.253:5060 [AP]
INVITE sip:support at 192.168.2.253 SIP/2.0..Via: SIP/2.0/TCP 192.168.2.28:167
74..Max-Forwards: 70..From: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e1
0b10e464cafe24f3603111429;epid=c83d59757b..To: <sip:support at 192.168.2.253>.
.Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28..CSeq: 1 INVITE..Co
ntact: <sip:test2 at 192.168.2.253:16774;maddr=192.168.2.28;transport=tcp>..Us
er-Agent: RTC/1.2..Content-Type: application/sdp..Content-Length: 743....v=
0..o=- 0 0 IN IP4 192.168.2.28..s=session..c=IN IP4 192.168.2.28..b=CT:1000
..t=0 0..m=audio 30948 RTP/AVP 97 111 112 6 0 8 4 5 3 101..k=base64:XiIN5Y9
5v66MtouJLXx6mA85W2ixQmXrwGBs2tkqy/k..a=rtpmap:97 red/8000..a=rtpmap:111 SI
REN/16000..a=fmtp:111 bitrate=16000..a=rtpmap:112 G7221/16000..a=fmtp:112 b
itrate=24000..a=rtpmap:6 DVI4/16000..a=rtpmap:0 PCMU/8000..a=rtpmap:8 PCMA/
8000..a=rtpmap:4 G723/8000..a=rtpmap:5 DVI4/8000..a=rtpmap:3 GSM/8000..a=rt
pmap:101 telephone-event/8000..a=fmtp:101 0-16..a=encryption:optional..m=vi
deo 16194 RTP/AVP 34 31..k=base64:how2kEmp54eFkxnMWyFIgrg6q6QcL8vXMnfuTiATT
cU..a=rtpmap:34 H263/90000..a=rtpmap:31 H261/90000..a=encryption:optional..
m=application 1503 tcp msdata..a=sendonly..a=encryption:optional..
T 192.168.2.253:5060 -> 192.168.2.28:1071 [AP]
SIP/2.0 100 trying -- your call is important to us..Via: SIP/2.0/TCP 192.16
8.2.28:16774..From: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464
cafe24f3603111429;epid=c83d59757b..To: <sip:support at 192.168.2.253>..Call-ID
: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28..CSeq: 1 INVITE..Server: Si
p EXpress router (0.8.14 (i386/linux))..Content-Length: 0..Warning: 392 192
.168.2.253:5060 "Noisy feedback tells: pid=10871 req_src_ip=192.168.2.28 r
eq_src_port=1071 in_uri=sip:support at 192.168.2.253 out_uri=sip:192.168.2.11:
7887;transport=tcp via_cnt==1"....
T 192.168.2.253:34183 -> 192.168.2.11:8346 [AP]
INVITE sip:192.168.2.11:8346;transport=tcp SIP/2.0..Record-Route: <sip:supp
ort at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603111429;lr=on
>..Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK2b66.68f50de5.1;i=6..Via: S
IP/2.0/TCP 192.168.2.28:16774..Max-Forwards: 69..From: "HS User" <sip:test2
@192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b..To: <
sip:support at 192.168.2.253>..Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.1
68.2.28..CSeq: 1 INVITE..Contact: <sip:test2 at 192.168.2.253:16774;maddr=192.
168.2.28;transport=tcp>..User-Agent: RTC/1.2..Content-Type: application/sdp
..Content-Length: 743....v=0..o=- 0 0 IN IP4 192.168.2.28..s=session..c=IN
IP4 192.168.2.28..b=CT:1000..t=0 0..m=audio 30948 RTP/AVP 97 111 112 6 0 8
4 5 3 101..k=base64:XiIN5Y95v66MtouJLXx6mA85W2ixQmXrwGBs2tkqy/k..a=rtpmap:9
7 red/8000..a=rtpmap:111 SIREN/16000..a=fmtp:111 bitrate=16000..a=rtpmap:11
2 G7221/16000..a=fmtp:112 bitrate=24000..a=rtpmap:6 DVI4/16000..a=rtpmap:0
PCMU/8000..a=rtpmap:8 PCMA/8000..a=rtpmap:4 G723/8000..a=rtpmap:5 DVI4/8000
..a=rtpmap:3 GSM/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-16..
a=encryption:optional..m=video 16194 RTP/AVP 34 31..k=base64:how2kEmp54eFkx
nMWyFIgrg6q6QcL8vXMnfuTiATTcU..a=rtpmap:34 H263/90000..a=rtpmap:31 H261/900
00..a=encryption:optional..m=application 1503 tcp msdata..a=sendonly..a=enc
ryption:optional..
T 192.168.2.11:8346 -> 192.168.2.253:34183 [AP]
SIP/2.0 100 Trying..Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK2b66.68f50
de5.1;i=6..Via: SIP/2.0/TCP 192.168.2.28:16774..From: "HS User" <sip:test2@
192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b..To: <s
ip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442..Call-ID: de
f3451d4ee24d3a96514fd98cb7061b at 192.168.2.28..CSeq: 1 INVITE..User-Agent: RT
C/1.2..Content-Length: 0....
T 192.168.2.11:8346 -> 192.168.2.253:34183 [AP]
SIP/2.0 180 Ringing..Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK2b66.68f5
0de5.1;i=6..Via: SIP/2.0/TCP 192.168.2.28:16774..From: "HS User" <sip:test2
@192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b..To: <
sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442..Call-ID: d
ef3451d4ee24d3a96514fd98cb7061b at 192.168.2.28..CSeq: 1 INVITE..Record-Route:
<sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f360311
1429;lr=on>..User-Agent: RTC/1.2..Content-Length: 0....
T 192.168.2.253:5060 -> 192.168.2.28:1071 [AP]
SIP/2.0 180 Ringing..Via: SIP/2.0/TCP 192.168.2.28:16774..From: "HS User" <
sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d5975
7b..To: <sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442..C
all-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28..CSeq: 1 INVITE..Reco
rd-Route: <sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe
24f3603111429;lr=on>..User-Agent: RTC/1.2..Content-Length: 0....
T 192.168.2.11:8346 -> 192.168.2.253:34183 [AP]
SIP/2.0 200 OK..Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK2b66.68f50de5.
1;i=6..Via: SIP/2.0/TCP 192.168.2.28:16774..From: "HS User" <sip:test2 at 192.
168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b..To: <sip:s
upport at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442..Call-ID: def345
1d4ee24d3a96514fd98cb7061b at 192.168.2.28..CSeq: 1 INVITE..Record-Route: <sip
:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603111429;
lr=on>..Contact: <sip:support at 192.168.2.253:8346;maddr=192.168.2.11;transpo
rt=tcp>..User-Agent: RTC/1.2..Content-Type: application/sdp..Content-Length
: 743....v=0..o=- 0 0 IN IP4 192.168.2.11..s=session..c=IN IP4 192.168.2.11
..b=CT:1000..t=0 0..m=audio 55856 RTP/AVP 97 111 112 6 0 8 4 5 3 101..k=bas
e64:kTDEaBWz86Ak1TG1jfVz6ei1Y1H7ov8t2/brmct4Xzs..a=rtpmap:97 red/8000..a=rt
pmap:111 SIREN/16000..a=fmtp:111 bitrate=16000..a=rtpmap:112 G7221/16000..a
=fmtp:112 bitrate=24000..a=rtpmap:6 DVI4/16000..a=rtpmap:0 PCMU/8000..a=rtp
map:8 PCMA/8000..a=rtpmap:4 G723/8000..a=rtpmap:5 DVI4/8000..a=rtpmap:3 GSM
/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-16..a=encryption:opt
ional..m=video 17516 RTP/AVP 34 31..k=base64:dg209s9MEye/smA4gDKBIqEZQvBLyV
1BzhKaPzZcIsg..a=rtpmap:34 H263/90000..a=rtpmap:31 H261/90000..a=encryption
:optional..m=application 1503 tcp msdata..a=recvonly..a=encryption:optional
..
T 192.168.2.253:5060 -> 192.168.2.28:1071 [AP]
SIP/2.0 200 OK..Via: SIP/2.0/TCP 192.168.2.28:16774..From: "HS User" <sip:t
est2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b..T
o: <sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442..Call-I
D: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28..CSeq: 1 INVITE..Record-Ro
ute: <sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f36
03111429;lr=on>..Contact: <sip:support at 192.168.2.253:8346;maddr=192.168.2.1
1;transport=tcp>..User-Agent: RTC/1.2..Content-Type: application/sdp..Conte
nt-Length: 743....v=0..o=- 0 0 IN IP4 192.168.2.11..s=session..c=IN IP4 192
.168.2.11..b=CT:1000..t=0 0..m=audio 55856 RTP/AVP 97 111 112 6 0 8 4 5 3 1
01..k=base64:kTDEaBWz86Ak1TG1jfVz6ei1Y1H7ov8t2/brmct4Xzs..a=rtpmap:97 red/8
000..a=rtpmap:111 SIREN/16000..a=fmtp:111 bitrate=16000..a=rtpmap:112 G7221
/16000..a=fmtp:112 bitrate=24000..a=rtpmap:6 DVI4/16000..a=rtpmap:0 PCMU/80
00..a=rtpmap:8 PCMA/8000..a=rtpmap:4 G723/8000..a=rtpmap:5 DVI4/8000..a=rtp
map:3 GSM/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-16..a=encry
ption:optional..m=video 17516 RTP/AVP 34 31..k=base64:dg209s9MEye/smA4gDKBI
qEZQvBLyV1BzhKaPzZcIsg..a=rtpmap:34 H263/90000..a=rtpmap:31 H261/90000..a=e
ncryption:optional..m=application 1503 tcp msdata..a=recvonly..a=encryption
:optional..
T 192.168.2.28:1071 -> 192.168.2.253:5060 [AP]
ACK sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603
111429;lr=on SIP/2.0..Via: SIP/2.0/TCP 192.168.2.28:16774..Max-Forwards: 70
..From: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f36031
11429;epid=c83d59757b..To: <sip:support at 192.168.2.253>;tag=081ad1daf478474a
bd2df8a5c6b2c442..Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28..C
Seq: 1 ACK..Route: <sip:support at 192.168.2.253:8346;maddr=192.168.2.11;trans
port=tcp>..User-Agent: RTC/1.2..Content-Length: 0....
T 192.168.2.28:1071 -> 192.168.2.253:5060 [AP]
INVITE sip:support at 192.168.2.253 SIP/2.0..Via: SIP/2.0/TCP 192.168.2.28:167
74..Max-Forwards: 70..From: "HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a
00746455296536eb88222dd86;epid=c83d59757b..To: <sip:support at 192.168.2.253>.
.Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28..CSeq: 1 INVITE..Co
ntact: <sip:test2 at 192.168.2.253:16774;maddr=192.168.2.28;transport=tcp>..Us
er-Agent: RTC/1.2..Roster-Manager: sip:test2 at 192.168.2.253..EndPoints: "HS
User" <sip:test2 at 192.168.2.253>, <sip:support at 192.168.2.253>..Supported: co
m.microsoft.rtc-multiparty..Content-Type: application/sdp..Content-Length:
105....v=0..o=- 0 0 IN IP4 192.168.2.28..s=session..c=IN IP4 192.168.2.28..
t=0 0..m=x-ms-message 5060 sip null..
T 192.168.2.253:5060 -> 192.168.2.28:1071 [AP]
SIP/2.0 100 trying -- your call is important to us..Via: SIP/2.0/TCP 192.16
8.2.28:16774..From: "HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455
296536eb88222dd86;epid=c83d59757b..To: <sip:support at 192.168.2.253>..Call-ID
: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28..CSeq: 1 INVITE..Server: Si
p EXpress router (0.8.14 (i386/linux))..Content-Length: 0..Warning: 392 192
.168.2.253:5060 "Noisy feedback tells: pid=10871 req_src_ip=192.168.2.28 r
eq_src_port=1071 in_uri=sip:support at 192.168.2.253 out_uri=sip:192.168.2.11:
7887;transport=tcp via_cnt==1"....
T 192.168.2.253:34183 -> 192.168.2.11:8346 [AP]
INVITE sip:192.168.2.11:8346;transport=tcp SIP/2.0..Record-Route: <sip:supp
ort at 192.168.2.253;transport=tcp;ftag=e1f4c4a00746455296536eb88222dd86;lr=on
>..Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK17fb.48e182d5.1;i=6..Via: S
IP/2.0/TCP 192.168.2.28:16774..Max-Forwards: 69..From: "HS User" <sip:test2
@192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b..To: <
sip:support at 192.168.2.253>..Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.1
68.2.28..CSeq: 1 INVITE..Contact: <sip:test2 at 192.168.2.253:16774;maddr=192.
168.2.28;transport=tcp>..User-Agent: RTC/1.2..Roster-Manager: sip:test2 at 192
.168.2.253..EndPoints: "HS User" <sip:test2 at 192.168.2.253>, <sip:support at 19
2.168.2.253>..Supported: com.microsoft.rtc-multiparty..Content-Type: applic
ation/sdp..Content-Length: 105....v=0..o=- 0 0 IN IP4 192.168.2.28..s=sessi
on..c=IN IP4 192.168.2.28..t=0 0..m=x-ms-message 5060 sip null..
T 192.168.2.11:8346 -> 192.168.2.253:34183 [AP]
SIP/2.0 100 Trying..Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK17fb.48e18
2d5.1;i=6..Via: SIP/2.0/TCP 192.168.2.28:16774..From: "HS User" <sip:test2@
192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b..To: <s
ip:support at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600..Call-ID: 87
c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28..CSeq: 1 INVITE..User-Agent: RT
C/1.2..Content-Length: 0....
T 192.168.2.11:8346 -> 192.168.2.253:34183 [AP]
SIP/2.0 200 OK..Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK17fb.48e182d5.
1;i=6..Via: SIP/2.0/TCP 192.168.2.28:16774..From: "HS User" <sip:test2 at 192.
168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b..To: <sip:s
upport at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600..Call-ID: 87c707
2355094ec39dc9e39eb5de5aa6 at 192.168.2.28..CSeq: 1 INVITE..Record-Route: <sip
:support at 192.168.2.253;transport=tcp;ftag=e1f4c4a00746455296536eb88222dd86;
lr=on>..Contact: <sip:support at 192.168.2.253:8346;maddr=192.168.2.11;transpo
rt=tcp>..User-Agent: RTC/1.2..Supported: com.microsoft.rtc-multiparty..Cont
ent-Type: application/sdp..Content-Length: 105....v=0..o=- 0 0 IN IP4 192.1
68.2.11..s=session..c=IN IP4 192.168.2.11..t=0 0..m=x-ms-message 5060 sip n
ull..
T 192.168.2.253:5060 -> 192.168.2.28:1071 [AP]
SIP/2.0 200 OK..Via: SIP/2.0/TCP 192.168.2.28:16774..From: "HS User" <sip:t
est2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b..T
o: <sip:support at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600..Call-I
D: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28..CSeq: 1 INVITE..Record-Ro
ute: <sip:support at 192.168.2.253;transport=tcp;ftag=e1f4c4a00746455296536eb8
8222dd86;lr=on>..Contact: <sip:support at 192.168.2.253:8346;maddr=192.168.2.1
1;transport=tcp>..User-Agent: RTC/1.2..Supported: com.microsoft.rtc-multipa
rty..Content-Type: application/sdp..Content-Length: 105....v=0..o=- 0 0 IN
IP4 192.168.2.11..s=session..c=IN IP4 192.168.2.11..t=0 0..m=x-ms-message 5
060 sip null..
T 192.168.2.28:1071 -> 192.168.2.253:5060 [AP]
ACK sip:support at 192.168.2.253;transport=tcp;ftag=e1f4c4a00746455296536eb882
22dd86;lr=on SIP/2.0..Via: SIP/2.0/TCP 192.168.2.28:16774..Max-Forwards: 70
..From: "HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb8822
2dd86;epid=c83d59757b..To: <sip:support at 192.168.2.253>;tag=001effba0777449e
996aafc0321dd600..Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28..C
Seq: 1 ACK..Route: <sip:support at 192.168.2.253:8346;maddr=192.168.2.11;trans
port=tcp>..User-Agent: RTC/1.2..Content-Length: 0....
T 192.168.2.11:1143 -> 192.168.2.253:5060 [AP]
BYE sip:support at 192.168.2.253;transport=tcp;ftag=455e2e10b10e464cafe24f3603
111429;lr=on SIP/2.0..Via: SIP/2.0/TCP 192.168.2.11:8346..Max-Forwards: 70.
.From: <sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442..To
: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;
epid=c83d59757b..Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28..CS
eq: 1 BYE..Route: <sip:test2 at 192.168.2.253:16774;maddr=192.168.2.28;transpo
rt=tcp>..User-Agent: RTC/1.2..Content-Length: 0....
T 192.168.2.11:1143 -> 192.168.2.253:5060 [AP]
BYE sip:support at 192.168.2.253;transport=tcp;ftag=e1f4c4a00746455296536eb882
22dd86;lr=on SIP/2.0..Via: SIP/2.0/TCP 192.168.2.11:8346..Max-Forwards: 70.
.From: <sip:support at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600..To
: "HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;
epid=c83d59757b..Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28..CS
eq: 1 BYE..Route: <sip:test2 at 192.168.2.253:16774;maddr=192.168.2.28;transpo
rt=tcp>..User-Agent: RTC/1.2..Content-Length: 0....
T 192.168.2.253:34183 -> 192.168.2.11:8346 [AP]
BYE sip:192.168.2.11:8346;transport=tcp SIP/2.0..Record-Route: <sip:support
@192.168.2.253;transport=tcp;ftag=081ad1daf478474abd2df8a5c6b2c442;lr=on>..
Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK2b66.78f50de5.1;i=9..Via: SIP/
2.0/TCP 192.168.2.11:8346..Max-Forwards: 69..From: <sip:support at 192.168.2.2
53>;tag=081ad1daf478474abd2df8a5c6b2c442..To: "HS User" <sip:test2 at 192.168.
2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59757b..Call-ID: def34
51d4ee24d3a96514fd98cb7061b at 192.168.2.28..CSeq: 1 BYE..User-Agent: RTC/1.2.
.Content-Length: 0....
T 192.168.2.253:34183 -> 192.168.2.11:8346 [AP]
BYE sip:192.168.2.11:8346;transport=tcp SIP/2.0..Record-Route: <sip:support
@192.168.2.253;transport=tcp;ftag=001effba0777449e996aafc0321dd600;lr=on>..
Via: SIP/2.0/TCP 192.168.2.253;branch=z9hG4bK17fb.58e182d5.1;i=9..Via: SIP/
2.0/TCP 192.168.2.11:8346..Max-Forwards: 69..From: <sip:support at 192.168.2.2
53>;tag=001effba0777449e996aafc0321dd600..To: "HS User" <sip:test2 at 192.168.
2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59757b..Call-ID: 87c70
72355094ec39dc9e39eb5de5aa6 at 192.168.2.28..CSeq: 1 BYE..User-Agent: RTC/1.2.
.Content-Length: 0....
T 192.168.2.11:8346 -> 192.168.2.253:34183 [AP]
SIP/2.0 481 Call Leg/Transaction Does Not Exist..Via: SIP/2.0/TCP 192.168.2
.253;branch=z9hG4bK2b66.78f50de5.1;i=9..Via: SIP/2.0/TCP 192.168.2.11:8346.
.From: <sip:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442..To
: "HS User" <sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;
epid=c83d59757b..Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28..CS
eq: 1 BYE..User-Agent: RTC/1.2..Content-Length: 0....
T 192.168.2.11:8346 -> 192.168.2.253:34183 [AP]
SIP/2.0 481 Call Leg/Transaction Does Not Exist..Via: SIP/2.0/TCP 192.168.2
.253;branch=z9hG4bK17fb.58e182d5.1;i=9..Via: SIP/2.0/TCP 192.168.2.11:8346.
.From: <sip:support at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600..To
: "HS User" <sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;
epid=c83d59757b..Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28..CS
eq: 1 BYE..User-Agent: RTC/1.2..Content-Length: 0....
T 192.168.2.253:5060 -> 192.168.2.11:1143 [AP]
SIP/2.0 408 Request Timeout..Via: SIP/2.0/TCP 192.168.2.11:8346..From: <sip
:support at 192.168.2.253>;tag=081ad1daf478474abd2df8a5c6b2c442..To: "HS User"
<sip:test2 at 192.168.2.253>;tag=455e2e10b10e464cafe24f3603111429;epid=c83d59
757b..Call-ID: def3451d4ee24d3a96514fd98cb7061b at 192.168.2.28..CSeq: 1 BYE..
Server: Sip EXpress router (0.8.14 (i386/linux))..Content-Length: 0..Warnin
g: 392 192.168.2.253:5060 "Noisy feedback tells: pid=10863 req_src_ip=192.
168.2.11 req_src_port=1143 in_uri=sip:support at 192.168.2.253;transport=tcp;f
tag=455e2e10b10e464cafe24f3603111429;lr=on out_uri=sip:192.168.2.11:7887;tr
ansport=tcp via_cnt==0"....
T 192.168.2.253:5060 -> 192.168.2.11:1143 [AP]
SIP/2.0 408 Request Timeout..Via: SIP/2.0/TCP 192.168.2.11:8346..From: <sip
:support at 192.168.2.253>;tag=001effba0777449e996aafc0321dd600..To: "HS User"
<sip:test2 at 192.168.2.253>;tag=e1f4c4a00746455296536eb88222dd86;epid=c83d59
757b..Call-ID: 87c7072355094ec39dc9e39eb5de5aa6 at 192.168.2.28..CSeq: 1 BYE..
Server: Sip EXpress router (0.8.14 (i386/linux))..Content-Length: 0..Warnin
g: 392 192.168.2.253:5060 "Noisy feedback tells: pid=10863 req_src_ip=192.
168.2.11 req_src_port=1143 in_uri=sip:support at 192.168.2.253;transport=tcp;f
tag=e1f4c4a00746455296536eb88222dd86;lr=on out_uri=sip:192.168.2.11:7887;tr
ansport=tcp via_cnt==0"....
-------------- next part --------------
# ----------- global configuration parameters ------------------------
#fork=no
listen=192.168.2.253
log_stderror=yes
debug=7
check_via=no # (cmd. line: -v)
dns=no # (cmd. line: -r)
rev_dns=no # (cmd. line: -R)
#port=5060
#children=4
fifo="/tmp/ser_fifo"
# ------------------ module loading ----------------------------------
loadmodule "/usr/lib/ser/modules/mysql.so"
loadmodule "/usr/lib/ser/modules/exec.so"
loadmodule "/usr/lib/ser/modules/sl.so"
loadmodule "/usr/lib/ser/modules/tm.so"
loadmodule "/usr/lib/ser/modules/rr.so"
loadmodule "/usr/lib/ser/modules/maxfwd.so"
loadmodule "/usr/lib/ser/modules/usrloc.so"
loadmodule "/usr/lib/ser/modules/registrar.so"
loadmodule "/usr/lib/ser/modules/textops.so"
loadmodule "/usr/lib/ser/modules/auth.so"
loadmodule "/usr/lib/ser/modules/auth_db.so"
# ----------------- setting module-specific parameters ---------------
modparam("usrloc", "db_mode", 0)
modparam("auth_db", "calculate_ha1", yes)
modparam("auth_db", "db_url", "sql://xxxx:xxxxx@127.0.0.1/hsver3")
modparam("auth_db", "user_column", "uname")
modparam("auth_db", "domain_column", "domain")
modparam("auth_db", "password_column", "pass")
modparam("rr", "enable_full_lr", 1)
# ------------------------- request routing logic -------------------
# main routing logic
alias="192.168.2.253"
route{
# initial sanity checks -- messages with
# max_forwards==0, or excessively long requests
if (!mf_process_maxfwd_header("10")) {
sl_send_reply("483","Too Many Hops");
break;
};
if (msg:len > 8192) {
sl_send_reply("513", "Message too big");
break;
};
record_route();
if (loose_route()) {
subst_uri("/(sip:)([a-zA-Z0-9_.!~*'()%&=+$,;?-]+@)?([a-zA-Z0-9.-]+)(:[0-9]+)?(;.*)(maddr=)([0-9.]{7,15})/\1\2\7\4\5\6\7/");
t_relay();
break;
}
if (uri==myself) {
if (method=="REGISTER") {
# Uncomment this if you want to use digest authentication
if (!proxy_authorize("", "users")) {
proxy_challenge("", "0");
break;
};
log(1,"---------- PROCESSING Register!!!!\n");
exec_msg('/etc/scripts/siplogin.php');
save("location");
break;
};
# native SIP destinations are handled using our USRLOC DB
if (!lookup("location")) {
sl_send_reply("404", "Not Found");
break;
};
};
if (method=="INVITE" || method=="ACK"){
log(1,"---------- PROCESSING INVITE/ACK!!!!\n");
exec_msg('/etc/scripts/sipinvite.php');
};
if (method=="BYE" && !search("P-Hint: repeat")){
log(1,"---------- PROCESSING BYE!!!!\n");
exec_msg('/etc/scripts/sipinvite.php');
};
# forward to current uri now; use stateful forwarding; that
# works reliably even if we forward from TCP to UDP
if (!t_relay()) {
sl_reply_error();
};
}
More information about the Serdev
mailing list