Hi,
It looks to me you are trying to compare apples with pears :)
In the 3.1 test, the TCP connection was found as already existing, while
in the 3.2 test no TCP conn was found and it is proceeding to opening a
new one -> so, yeah, different cases, different logs :)
Regards,
Bogdan-Andrei Iancu
OpenSIPS Founder and Developer
https://www.opensips-solutions.com
OpenSIPS Summit 27-30 Sept 2022, Athens
https://www.opensips.org/events/Summit-2022Athens/
On 9/26/22 11:39 AM, jacky z wrote:
Hi Team,
We are testing Opensips 3.2.8 and found it can't send stored offline
messages on register. Compared with 3.1, it doesn't look for the tcp
con. Here are the comparisons between the logs of these two versions:
In 3.1, the existing con was looked and found and then the message was
sent. Please refer to the texts in red.
Sep 26 08:09:56 opensips[11566]: DBG:tm:print_request_uri:
sip:3293543...@sip.domain.com:5061
<http://sip:3293543...@sip.domain.com:5061>
Sep 26 08:09:56 opensips[11566]: DBG:tm:run_local_route: building
sip_msg from buffer
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_msg: SIP Request:
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_msg: method: <MESSAGE>
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_msg: uri:
<sip:3293543...@sip.domain.com:5061
<http://sip:3293543...@sip.domain.com:5061>>
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_msg: version: <SIP/2.0>
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_headers:
flags=ffffffffffffffff
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_via_param: found param
type 232, <branch> = <z9hG4bKc2f3.b2d09993.0>; state=16
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_via: end of header
reached, state=5
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_headers: via found,
flags=ffffffffffffffff
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_headers: this is the
first via
Sep 26 08:09:56 opensips[11566]: DBG:core:_parse_to: end of header
reached, state=9
Sep 26 08:09:56 opensips[11566]: DBG:core:_parse_to: display={},
ruri={sip:3293543...@sip.domain.com:5061
<http://sip:3293543...@sip.domain.com:5061>}
Sep 26 08:09:56 opensips[11566]: DBG:core:get_hdr_field: <To> [38];
uri=[sip:3293543...@sip.domain.com:5061
<http://sip:3293543...@sip.domain.com:5061>]
Sep 26 08:09:56 opensips[11566]: DBG:core:get_hdr_field: to body
[sip:3293543...@sip.domain.com:5061#015#012
<http://sip:3293543...@sip.domain.com:5061#015#012>]
Sep 26 08:09:56 opensips[11566]: DBG:core:get_hdr_field: cseq <CSeq>:
<10> <MESSAGE>
Sep 26 08:09:56 opensips[11566]: DBG:core:get_hdr_field: content_length=28
Sep 26 08:09:56 opensips[11566]: DBG:core:get_hdr_field: found end of
header
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_headers:
flags=ffffffffffffffff
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_headers: flags=78
*Sep 26 08:09:56 opensips[11566]: DBG:core:tcp_conn_get: con found in
state 0*
*Sep 26 08:09:56 opensips[11566]: DBG:core:tcp_conn_get: tcp
connection found (0x7fd4544f8130), acquiring fd*
*Sep 26 08:09:56 opensips[11566]: DBG:core:tcp_conn_get: c=
0x7fd4544f8130, n=16, Usock=89*
Sep 26 08:09:56 opensips[11571]: DBG:core:handle_worker: read
response= 7fd4544f8130, 1, fd -1 from 9 (11566)
Sep 26 08:09:56 opensips[11566]: DBG:core:tcp_conn_get: after
receive_fd: c= 0x7fd4544f8130 n=8 fd=118
Sep 26 08:09:56 opensips[11566]: DBG:proto_tls:proto_tls_send: sending
via fd 118...
Sep 26 08:09:56 opensips[11566]: DBG:proto_tls:tls_update_fd: New fd
is 118
Sep 26 08:09:56 opensips[11566]: DBG:proto_tls:tls_write: write was
successful (555 bytes)
In 3.2.8, it seems the tcp connection was not looked for or found.
There is no tcp_conn_get as shown in the logs of 3.1, but reach the
conclusion no tcp connection found. It seems something is missing.
Sep 26 08:18:33 opensips[3481]: DBG:tm:run_local_route: building
sip_msg from buffer
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_msg: SIP Request:
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_msg: method: <MESSAGE>
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_msg: uri:
<sip:6989229...@sip.domain.com <mailto:sip%3a6989229...@sip.domain.com>>
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_msg: version: <SIP/2.0>
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_headers:
flags=ffffffffffffffff
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_via_param: found param
type 232, <branch> = <z9hG4bK2c0f.5dc63a16.0>; state=16
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_via: end of header
reached, state=5
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_headers: via found,
flags=ffffffffffffffff
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_headers: this is the
first via
Sep 26 08:18:33 opensips[3481]: DBG:core:_parse_to: end of header
reached, state=9
Sep 26 08:18:33 opensips[3481]: DBG:core:_parse_to: display={},
ruri={sip:6989229...@sip.domain.com
<mailto:sip%3a6989229...@sip.domain.com>}
Sep 26 08:18:33 opensips[3481]: DBG:core:get_hdr_field: <To> [32];
uri=[sip:6989229...@sip.domain.com
<mailto:sip%3a6989229...@sip.domain.com>]
Sep 26 08:18:33 opensips[3481]: DBG:core:get_hdr_field: to body
[sip:6989229...@sip.domain.com#015#012
<http://sip:6989229...@sip.domain.com#015#012>]
Sep 26 08:18:33 opensips[3481]: DBG:core:get_hdr_field: cseq <CSeq>:
<10> <MESSAGE>
Sep 26 08:18:33 opensips[3481]: DBG:core:get_hdr_field: content_length=36
Sep 26 08:18:33 opensips[3481]: DBG:core:get_hdr_field: found end of
header
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_headers:
flags=ffffffffffffffff
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_headers: flags=78
*Sep 26 08:18:33 opensips[3481]: DBG:proto_tls:proto_tls_send: no open
tcp connection found, opening new one, async = 0*
Thank you!
_______________________________________________
Users mailing list
Users@lists.opensips.org
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
_______________________________________________
Users mailing list
Users@lists.opensips.org
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
