[Kamailio-Users] openser 1.2.1 errors in the log - tcp blocking

[toly]

Here's another example:

08/21 19:19:57 8012 notice  <xlog.c: 148> TRACKING [0] NEW REQUEST -
M=MESSAGE RURI=sip:zahmad at dotnet.com F=sip:apritiki at dotnet.com
T=sip:zahmad at dotnet.com IP=64.10
8.238.66 ID=64.108.238.66
08/21 19:19:57 8012 notice  <xlog.c: 148> TRACKING [0] RECORD ROUTE -
M=MESSAGE RURI=sip:zahmad at dotnet.com F=sip:apritiki at dotnet.com
T=sip:zahmad at dotnet.com IP=64.1
08.238.66 ID=64.108.238.66
08/21 19:19:57 8012 debug  <parser/msg_parser.c: 310> parse_headers:
flags=ffffffffffffffff
08/21 19:19:57 8012 debug  <parser/msg_parser.c: 153> get_hdr_field: cseq
<CSeq>: <10> <MESSAGE>
08/21 19:19:57 8012 debug  <parser/msg_parser.c: 193> DEBUG: get_hdr_body :
content_length=94
08/21 19:19:57 8012 debug  <parser/msg_parser.c: 82> found end of header
08/21 19:19:57 8012 debug  <parser/msg_parser.c: 310> parse_headers:
flags=200
08/21 19:19:57 8012 debug  <loose.c: 115> find_first_route: No Route headers
found
08/21 19:19:57 8012 debug  <loose.c: 883> loose_route: There is no Route HF
08/21 19:19:57 8012 notice  <xlog.c: 148> TRACKING [14] ROUTE14 - M=MESSAGE
RURI=sip:zahmad at dotnet.com F=sip:apritiki at dotnet.com T=sip:zahmad at dotnet.com
IP=64.108.2
38.66 ID=64.108.238.66
08/21 19:19:57 8012 debug  <dset.c: 445> rewrite_uri: Rewriting Request-URI
with
'sip:zahmad at 64.108.238.10:9903;transport=TCP;rinstance=1e9ccca98d9e9478'
08/21 19:19:57 8012 debug  <dset.c: 285> ******* setting for branch 0 flags
C0
08/21 19:19:57 8012 notice  <xlog.c: 148> TRACKING [1] - M=MESSAGE
RURI=sip:zahmad at 64.108.238.10:9903;transport=TCP;rinstance=1e9ccca98d9e9478
D-URI=sip:64.108.238.10:990
3;transport=TCP  F=sip:apritiki at dotnet.com T=sip:zahmad at dotnet.com
IP=64.108.238.66 ID=64.108.238.66
08/21 19:19:57 8012 notice  <xlog.c: 148> TRACKING [1] USING NATed reply -
M=MESSAGE
RURI=sip:zahmad at 64.108.238.10:9903;transport=TCP;rinstance=1e9ccca98d9e9478
D-URI=sip
:64.108.238.10:9903;transport=TCP  F=sip:apritiki at dotnet.com
T=sip:zahmad at dotnet.com IP=64.108.238.66 ID=64.108.238.66
08/21 19:19:57 8012 debug  <t_lookup.c: 1037> DEBUG: t_newtran:  T on
entrance=0xffffffff
08/21 19:19:57 8012 debug  <parser/msg_parser.c: 310> parse_headers:
flags=ffffffffffffffff
08/21 19:19:57 8012 debug  <parser/msg_parser.c: 310> parse_headers:
flags=78
08/21 19:19:57 8012 debug  <t_lookup.c: 407> t_lookup_request: start
searching: hash=35557, isACK=0
08/21 19:19:57 8012 debug  <t_lookup.c: 364> DEBUG: RFC3261 transaction
matching failed
08/21 19:19:57 8012 debug  <t_lookup.c: 540> DEBUG: t_lookup_request: no
transaction found
08/21 19:19:57 8012 debug  <t_hooks.c: 229> DBG: trans=0xb65243e8, callback
type 1, id 0 entered
08/21 19:19:57 8012 debug  <parser/msg_parser.c: 310> parse_headers:
flags=78
08/21 19:19:57 8012 debug  <proxy.c: 251> DEBUG: mk_proxy: doing DNS
lookup...
08/21 19:19:57 8012 debug  <msg_translator.c: 1393> build_req_from_req: id
added: <;i=72>, rcv proto=2
08/21 19:19:57 8012 debug  <parser/msg_parser.c: 310> parse_headers:
flags=2000
08/21 19:19:57 8012 debug  <msg_translator.c: 166>
check_via_address(64.108.238.66, 192.168.0.101, 0)
08/21 19:19:57 8012 debug  <proxy.c: 251> DEBUG: mk_proxy: doing DNS
lookup...
08/21 19:19:57 8012 debug  <msg_translator.c: 1393> build_req_from_req: id
added: <;i=72>, rcv proto=2
08/21 19:19:57 8012 debug  <parser/msg_parser.c: 310> parse_headers:
flags=2000
08/21 19:19:57 8012 debug  <msg_translator.c: 166>
check_via_address(64.108.238.66, 192.168.0.101, 0)
08/21 19:19:57 8012 debug  <tcp_main.c: 718> tcp_send: no open tcp
connection found, opening new one


08/21 19:19:59 8012 err  <tcp_main.c: 291> ERROR: tcp_blocking_connect:
timeout 2 s elapsed from 2 s
08/21 19:19:59 8012 err  <tcp_main.c: 459> ERROR: tcpconn_connect:
tcp_blocking_connect failed
08/21 19:19:59 8012 err  <tcp_main.c: 721> ERROR: tcp_send: connect failed
08/21 19:19:59 8012 err  <../../forward.h: 105> msg_send: ERROR: tcp_send
failed
08/21 19:19:59 8012 err  <t_fwd.c: 767> ERROR:tm:t_forward_nonack: sending
request failed
08/21 19:19:59 8012 debug  <tcp_main.c: 743> tcp_send: tcp connection found
(0xb61cb6c8), acquiring fd
08/21 19:19:59 8012 debug  <tcp_main.c: 754> tcp_send, c= 0xb61cb6c8, n=8
08/21 19:19:59 8041 debug  <tcp_main.c: 1299> handle_ser_child: read
response= b61cb6c8, 1, fd -1 from 27 (8012)
08/21 19:19:59 8012 debug  <tcp_main.c: 773> tcp_send: after receive_fd: c=
0xb61cb6c8 n=4 fd=52
08/21 19:19:59 8012 debug  <tcp_main.c: 778> tcp_send: sending...
08/21 19:19:59 8012 debug  <tcp_main.c: 788> tcp_send: after write: c=
0xb61cb6c8 n=870 fd=52
08/21 19:19:59 8012 debug  <tcp_main.c: 789> tcp_send: buf= MESSAGE
sip:zahmad at 64.108.238.10:13025;transport=TCP;rinstance=35e37780e9a95b52
SIP/2.0  Record-Route: <sip:20
8.64.102.78:5070;transport=tcp;lr=on;ftag=8d17630f>  Via: SIP/2.0/TCP
333.64.102.78:5070;branch=z9hG4bK5ea8.1957702.1;i=72  Via: SIP/2.0/TCP
192.168.0.101:60000;received=
64.108.238.66;branch=z9hG4bK-d8754z-11718027f72c8f35-1---d8754z-;rport=60318 
Max-Forwards: 69  To: "Zishan Ahmad"<sip:zahmad at dotnet.com>  From: "Anatoly
Pritikin"<sip:
apritiki at dotnet.com>;tag=8d17630f  Call-ID:
NGM0OWZkMjZlZjI0N2QzYTk2ZjU2MjQyZDVlMzlhNDU.  CSeq: 10 MESSAGE  Allow:
INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MES
SAGE, SUBSCRIBE, INFO  Content-Type: text/html  User-Agent: Parus Networks
release 1.0 Internal RC1 stamp 48972  Content-Length: 94  P-hint:
rr-enforced    are you going to try?

-- 
View this message in context: http://www.nabble.com/openser-1.2.1-errors-in-the-log---tcp-blocking-tp19097573p19099767.html
Sent from the OpenSER Users Mailing List mailing list archive at Nabble.com.