Greetings,
This is openser 1.2.1 compiled with TLS, TLS is not turned on. This is part of the config file:
listen=xxx.xxx.xxx.xxx # This is where OpenSER installed port=5070 children=25
tcp_connect_timeout=2 tcp_max_connections=8192
reply_to_via = no sip_warning = yes check_via = no
dns=no rev_dns=no disable_dns_blacklist=true
here's the problem: I have 25 users having each other as a contact. Softphone is Bria, transport is tcp
here comes subscribe:
08/21 15:07:46 8029 debug <loose.c: 115> find_first_route: No Route headers found 08/21 15:07:46 8029 debug <loose.c: 883> loose_route: There is no Route HF 08/21 15:07:46 8029 notice <xlog.c: 148> TRACKING [18] - M=SUBSCRIBE RURI=sip:dmarkiew@tamtam.com F=sip:sgarratt@tamtam.com T=sip:dmarkiew@tamtam.com IP=333.64.100 .15 ID=YTBmOTJhNGI5M2RmZjYyNmM2NGFhMmI1NmRjMmY4ZWE. 08/21 15:07:46 8029 debug <dset.c: 445> rewrite_uri: Rewriting Request-URI with 'sip:dmarkiew@76.222.53.112:53363;transport=TCP;rinstance=575ebcf03ae72b26' 08/21 15:07:46 8029 debug <dset.c: 285> ******* setting for branch 0 flags C0 08/21 15:07:46 8029 debug <dset.c: 285> ******* setting for branch 1 flags C0 08/21 15:07:46 8029 notice <xlog.c: 148> TRACKING [1] - M=SUBSCRIBE RURI=sip:dmarkiew@76.222.53.112:53363;transport=TCP;rinstance=575ebcf03ae72b26 D-URI=sip:76.222.53.11 2:53363;transport=TCP F=sip:sgarratt@tamtam.com T=sip:dmarkiew@tamtam.com IP=333.64.100.15 ID=333.64.100.15 08/21 15:07:46 8029 notice <xlog.c: 148> TRACKING [1] USING NATed reply - M=SUBSCRIBE RURI=sip:dmarkiew@76.222.53.112:53363;transport=TCP;rinstance=575ebcf03ae72b26 D-UR I=sip:76.222.53.112:53363;transport=TCP F=sip:sgarratt@tamtam.com T=sip:dmarkiew@tamtam.com IP=333.64.100.15 ID=333.64.100.15 08/21 15:07:46 8029 debug <t_lookup.c: 1037> DEBUG: t_newtran: T on entrance=0xffffffff 08/21 15:07:46 8029 debug <parser/msg_parser.c: 310> parse_headers: flags=ffffffffffffffff 08/21 15:07:46 8029 debug <parser/msg_parser.c: 310> parse_headers: flags=78 08/21 15:07:46 8029 debug <t_lookup.c: 407> t_lookup_request: start searching: hash=41567, isACK=0
and later I get this:
08/21 15:07:48 8029 err <tcp_main.c: 291> ERROR: tcp_blocking_connect: timeout 2 s elapsed from 2 s 08/21 15:07:48 8029 err <tcp_main.c: 459> ERROR: tcpconn_connect: tcp_blocking_connect failed 08/21 15:07:48 8029 err <tcp_main.c: 721> ERROR: tcp_send: connect failed 08/21 15:07:48 8029 err <../../forward.h: 105> msg_send: ERROR: tcp_send failed 08/21 15:07:48 8029 err <t_fwd.c: 767> ERROR:tm:t_forward_nonack: sending request failed 08/21 15:07:48 8029 debug <tcp_main.c: 718> tcp_send: no open tcp connection found, opening new one
And I get a lot of it. Eventually I may get SO_ERROR(111) and then I have to restart proxy.
I can post entire config script if anybody had/resolved/willing to take a look at the problem.
Thanks a lot, Toly
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@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?
-
toly