[SR-Users] Fwd: Msilo not delivering message (status 408)

Fri Jul 18 18:40:23 CEST 2014

Thanks Daniel.
UDP is the same story as TCP and TLS, unfortunately.

Looking through the logs, I see strange errors in parsing the contact
header when msilo prepares to send the notification back to 1000.
This is for the notification message that should have been sent back to
1000 after he sent a message to 1010 (offline).
I also see that kamailio reports that 1000's location was not found, even
though he's obviously registered.

The more I play around with this, the more confused I become.

Do you think this contact parsing error could be the root of the problems?

Cheers,
Peter

Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/msg_parser.c:623]: parse_msg(): SIP Request:
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/msg_parser.c:625]: parse_msg():  method:  <MESSAGE>
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/msg_parser.c:627]: parse_msg():  uri:     <sip:1000 at my.domain.com>
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/msg_parser.c:629]: parse_msg():  version: <SIP/2.0>
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/parse_via.c:1284]: parse_via_param(): Found param type 232,
<branch> = <z9hG4bK90dc.68116a97000000000000000000000000.0>; state=16
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found,
flags=2
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the
first via
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[receive.c:152]: receive_msg(): After parse_msg...
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[receive.c:193]: receive_msg(): preparing to run routing scripts...
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/parse_addr_spec.c:893]: parse_addr_spec(): end of header reached,
state=10
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To>
[28]; uri=[sip:1000 at my.domain.com]
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [<
sip:1000 at my.domain.com>#015#012]
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>:
<10> <MESSAGE>
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: maxfwd
[mf_funcs.c:85]: is_maxfwd_present(): value = 70
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body :
content_length=25
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/msg_parser.c:106]: get_hdr_field(): found end of header
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/parse_addr_spec.c:176]: parse_to_param(): DEBUG: add_param:
tag=533cb9e91f4b999cf76861cbb9ed54ed-bb2e
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[parser/parse_addr_spec.c:893]: parse_addr_spec(): end of header reached,
state=29
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: sanity
[mod_sanity.c:255]: w_sanity_check(): sanity checks result: 1
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: siputils
[sipops.c:161]: w_is_first_hop(): no 2nd via found - first hop
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: *ERROR: <core>
[parser/contact/contact.c:175]: skip_name(): skip_name(): Error in contact,
scheme separator not found*
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: ERROR: <core>
[parser/contact/contact.c:202]: parse_contacts(): parse_contacts(): Error
while skipping name part
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: ERROR: <core>
[parser/contact/parse_contact.c:59]: contact_parser(): contact_parser():
Error while parsing contacts
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: ERROR: <core>
[parser/contact/parse_contact.c:88]: parse_contact(): parse_contact():
Error while parsing
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: ERROR: nathelper
[nhelpr_funcs.c:300]: get_contact_uri(): failed to parse Contact body
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: siputils
[checks.c:103]: has_totag(): no totag
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: NOTICE: <script>:
!------------------------------------>WE HAVE A NEW MESSAGE
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: registrar
[lookup.c:181]: *lookup(): contact for [1000] found by address*
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[socket_info.c:583]: grep_sock_info(): grep_sock_info - checking if
host==us: 14==9 && [79.my.server.IP1] == [127.0.0.1]
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: <core>
[socket_info.c:583]: grep_sock_info(): grep_sock_info - checking if
host==us: 14==14 && [79.my.server.IP1] == [79.my.server.IP1]
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: NOTICE: <script>:
------------------MESSAGE PROCESSING: *location not found*, storing offline
message ID: (sip:1000 at 85.xx.xx.xx:59287;transport=TLS)
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: msilo [msilo.c:605]:
m_store(): ------------ start ------------
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: msilo [msilo.c:648]:
m_store(): using user id [sip:1000 at 85.xx.xx.xx:59287;transport=TLS]
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: msilo [msilo.c:732]:
m_store(): 'From' header: <sip:registrar at my.domain.com>
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: msilo [msilo.c:849]:
m_store(): message stored. T:<sip:1000 at my.domain.com> F:<
sip:registrar at my.domain.com>
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: msilo [msilo.c:858]:
m_store(): sending info message.
Jul 18 15:50:32 pbx /usr/sbin/kamailio[12600]: DEBUG: msilo [msilo.c:899]:
m_store(): contact header found

On Thu, Jul 17, 2014 at 11:01 PM, Daniel-Constantin Mierla <
miconda at gmail.com> wrote:

>  You should not call m_store() if src_ip==myself -- in this way you avoid
> to store notification messages that cannot be delivered.
>
> Why notifications are not delivered has to be troubleshooted there. Have
> you tried also with UDP?
>
> Cheers,
> Daniel
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20140718/b9435c3d/attachment.html>