Re: [OpenSIPS-Users] OPENSIPS 3.2.8 msilo can't send offline message on Register

[Bogdan-Andrei Iancu]

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 

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:  
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_msg: uri:    
 >

Sep 26 08:09:56 opensips[11566]: DBG:core:parse_msg: version: 
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_headers: 
flags=
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_via_param: found param 
type 232,  = ; 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=
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 
}
Sep 26 08:09:56 opensips[11566]: DBG:core:get_hdr_field:  [38]; 
uri=[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 
]
Sep 26 08:09:56 opensips[11566]: DBG:core:get_hdr_field: cseq : 
<10> 

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=

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:  
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_msg: uri:    
 mailto:sip%3a6989229...@sip.domain.com>>

Sep 26 08:18:33 opensips[3481]: DBG:core:parse_msg: version: 
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_headers: 
flags=
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_via_param: found param 
type 232,  = ; 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=
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 
}
Sep 26 08:18:33 opensips[3481]: DBG:core:get_hdr_field:  [32]; 
uri=[sip:6989229...@sip.domain.com 
]
Sep 26 08:18:33 opensips[3481]: DBG:core:get_hdr_field: to body 
[sip:6989229...@sip.domain.com#015#012 

[OpenSIPS-Users] OPENSIPS 3.2.8 msilo can't send offline message on Register

[jacky z]

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
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:  
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_msg:  uri: <
sip:3293543...@sip.domain.com:5061>
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_msg:  version: 
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_headers:
flags=
Sep 26 08:09:56 opensips[11566]: DBG:core:parse_via_param: found param type
232,  = ; 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=
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}
Sep 26 08:09:56 opensips[11566]: DBG:core:get_hdr_field:  [38]; uri=[
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]
Sep 26 08:09:56 opensips[11566]: DBG:core:get_hdr_field: cseq : <10>

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=
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:  
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_msg:  uri: <
sip:6989229...@sip.domain.com>
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_msg:  version: 
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_headers:
flags=
Sep 26 08:18:33 opensips[3481]: DBG:core:parse_via_param: found param type
232,  = ; 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=
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}
Sep 26 08:18:33 opensips[3481]: DBG:core:get_hdr_field:  [32]; uri=[
sip:6989229...@sip.domain.com]
Sep 26 08:18:33 opensips[3481]: DBG:core:get_hdr_field: to body [
sip:6989229...@sip.domain.com#015#012]
Sep 26 08:18:33 opensips[3481]: DBG:core:get_hdr_field: cseq : <10>

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=
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