Here's another example:
08/21 19:19:57 8012 notice <xlog.c: 148> TRACKING [0] NEW REQUEST -
M=MESSAGE RURI=sip:zahmad@dotnet.com F=sip:apritiki@dotnet.com
T=sip:zahmad@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@dotnet.com F=sip:apritiki@dotnet.com
T=sip:zahmad@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@dotnet.com F=sip:apritiki@dotnet.com T=sip:zahmad@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@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@64.108.238.10:9903;transport=TCP;rinstance=1e9ccca98d9e9478
D-URI=sip:64.108.238.10:990
3;transport=TCP F=sip:apritiki@dotnet.com T=sip:zahmad@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@64.108.238.10:9903;transport=TCP;rinstance=1e9ccca98d9e9478
D-URI=sip
:64.108.238.10:9903;transport=TCP F=sip:apritiki@dotnet.com
T=sip:zahmad@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@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@dotnet.com> From:
"Anatoly
Pritikin"<sip:
apritiki(a)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-tp1909…
Sent from the OpenSER Users Mailing List mailing list archive at
Nabble.com.