[SR-Users] topos and PRACK problem

Thu Apr 27 11:29:21 CEST 2017

Hi, Daniel.

I just done one more test topos with re-invite which comes from caller
to callee, same direction as PRACK.

And I found that, in case of prack, b_contact field is empty...

Please find attached debug=3 part of re-invite message.

Hope it helps.
--
Best regards,
Sergey Basov                     e-mail: sergey.v.basov at gmail.com

2017-04-27 10:30 GMT+03:00 Sergey Basov <sergey.v.basov at gmail.com>:
> Hi, Daniel
>
> Please look at attached part of debug=3 while receiving and parsing PRACK.
> At line 208 seems rr module does not find correct part of record_route
> which in DB consists from 2 parts.
>
> So at line 219 and later uac module cannot restore uris.
>
> Thank you.
> --
> Best regards,
> Sergey Basov                     e-mail: sergey.v.basov at gmail.com
>
>
> 2017-04-26 17:08 GMT+03:00 Sergey Basov <sergey.v.basov at gmail.com>:
>> Thanks for workaround.
>>
>> But I will wait for you solution )
>>
>> I ready for testing )
>>
>> Thank you Daniel for your work!
>>
>> --
>> Best regards,
>> Sergey Basov                     e-mail: sergey.v.basov at gmail.com
>>
>>
>> 2017-04-26 16:57 GMT+03:00 Daniel-Constantin Mierla <miconda at gmail.com>:
>>> Hello,
>>>
>>>
>>> On 26.04.17 14:53, Sergey Basov wrote:
>>>> Hi All.
>>>>
>>>> I have just try to test topos with GW which requires PRACK.
>>>>
>>>> As you can see UA at packet 21 send PRACK to topos contact, but after
>>>> topos, on other kamailio side in PRACK request line present not
>>>> kontact but record-route header.
>>>>
>>>> Can you fix it?
>>>>
>>>>
>>> probably needs to look into the code. If you need a quick workaround,
>>> try to remove Supported header from INVITE so the callee should no
>>> longer Require 100rel.
>>>
>>> Cheers,
>>> Daniel
>>>
>>> --
>>> Daniel-Constantin Mierla
>>> www.twitter.com/miconda -- www.linkedin.com/in/miconda
>>> Kamailio Advanced Training - May 22-24 (USA) - www.asipto.com
>>> Kamailio World Conference - May 8-10, 2017 - www.kamailioworld.com
>>>
>>>
>>> _______________________________________________
>>> Kamailio (SER) - Users Mailing List
>>> sr-users at lists.kamailio.org
>>> https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
-------------- next part --------------
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:595]: parse_msg(): SIP Request:
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:597]: parse_msg():  method:  <INVITE>
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:599]: parse_msg():  uri:     <sip:atpsh-5901aaef-2aa8-3 at 212.58.160.253:5061;transport=tls>
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:601]: parse_msg():  version: <SIP/2.0>
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK-524287-1---c29d5f106e7a4975>; state=6
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=17
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:486]: parse_headers(): Via found, flags=2
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:488]: parse_headers(): this is the first via
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_addr_spec.c:171]: parse_to_param(): DEBUG: add_param: tag=10000005923062
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_addr_spec.c:871]: parse_addr_spec(): end of header reached, state=29
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [73]; uri=[sip:380637304473 at 212.58.160.253:5061;transport=TLS]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:172]: get_hdr_field(): to body [<sip:380637304473 at 212.58.160.253:5061;transport=TLS>]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <3> <INVITE>
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=305
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_addr_spec.c:171]: parse_to_param(): DEBUG: add_param: tag=2953e855
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_addr_spec.c:871]: parse_addr_spec(): end of header reached, state=29
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: topos [tps_msg.c:725]: tps_request_received(): handling incoming request
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: topos [tps_msg.c:371]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/TLS 100.80.183.29:5061;branch=z9hG4bK-524287-1---c29d5f106e7a4975;rport](79) - x_via2: [](0) - x_vbranch1: [z9hG4bK-524287-1---c29d5f106e7a4975](35)
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: topos [tps_msg.c:473]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0)
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: topos [tps_msg.c:476]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0)
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: topos [tps_storage.c:120]: tps_storage_lock_get(): tps lock get: 334
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:116]: db_new_result(): allocate 56 bytes for result set at 0x7f5350b8ea98
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:66]: db_mysql_get_columns(): 21 columns returned from the query
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:154]: db_allocate_columns(): allocate 168 bytes for result names at 0x7f5350bcb3b0
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:165]: db_allocate_columns(): allocate 84 bytes for result types at 0x7f5350b925f0
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f53508b1c18
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508b1c18)[0]=[rectime]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:115]: db_mysql_get_columns(): use DB1_DATETIME result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[1] at 0x7f53508b2a60
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508b2a60)[1]=[a_callid]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[2] at 0x7f5350b23588
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f5350b23588)[2]=[a_uuid]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[3] at 0x7f53508affc0
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508affc0)[3]=[b_uuid]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[4] at 0x7f5350b23a10
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f5350b23a10)[4]=[a_contact]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[5] at 0x7f53508adab0
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508adab0)[5]=[b_contact]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[6] at 0x7f53508b1708
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508b1708)[6]=[as_contact]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[7] at 0x7f53508ae120
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508ae120)[7]=[bs_contact]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[8] at 0x7f53508afa18
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508afa18)[8]=[a_tag]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[9] at 0x7f53508aee00
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508aee00)[9]=[b_tag]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[10] at 0x7f5350b21608
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f5350b21608)[10]=[a_rr]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[11] at 0x7f53508ad448
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508ad448)[11]=[b_rr]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[12] at 0x7f5350b229d8
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f5350b229d8)[12]=[s_rr]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[13] at 0x7f53508b0640
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508b0640)[13]=[iflags]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:99]: db_mysql_get_columns(): use DB1_INT result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[14] at 0x7f53508af470
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508af470)[14]=[a_uri]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[15] at 0x7f53508ae790
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508ae790)[15]=[b_uri]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[16] at 0x7f53508b2638
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508b2638)[16]=[r_uri]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[17] at 0x7f53508b2128
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508b2128)[17]=[a_srcaddr]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[18] at 0x7f53508b11f8
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f53508b11f8)[18]=[b_srcaddr]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[19] at 0x7f5350b8ec78
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f5350b8ec78)[19]=[s_method]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[20] at 0x7f5350bcb160
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f5350bcb160)[20]=[s_cseq]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:184]: db_allocate_rows(): allocate 16 bytes for rows at 0x7f5350bcaee8
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_row.c:117]: db_allocate_row(): allocate 672 bytes for row values at 0x7f5350aeb0a0
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:158]: db_str2val(): converting DATETIME [2017-04-27 12:13:52]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING [xMPIakb6Hm2d64XzzmkKwg..]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING [atpsh-5901aaef-2aa8-3]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING [btpsh-5901aaef-2aa8-3]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING [sip:380931701931 at 37.73.206.8:29300;transport=tls]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING [sip:KITS1.MSS.LIFE.COM:5060;transport=UDP]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING [<sip:atpsh-5901aaef-2aa8-3 at 212.58.160.253:5061;transport=tls>]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING [<sip:btpsh-5901aaef-2aa8-3 at 10.56.42.33>]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING [2953e855]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING [10000005923062]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:169]: db_str2val(): converting BLOB []
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:169]: db_str2val(): converting BLOB [<sip:KITS1.MSS.LIFE.COM:5060;transport=UDP;lr>]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:169]: db_str2val(): converting BLOB [<sip:10.56.42.33;r2=on;lr;ftag=2953e855;did=d9.e631;vsf=AAAAAAoLAQ4DAA4DAHlnYg5heGIAeXFodBhzYX8PBgoDAA1ESBEAXB4bHwEXdD1UTFM-;vst=AAAAAAMOAwEABwcEAwd3AnAcBAQAAwsAAAYKGwIDAwFAQ1NDBgQaDgAWcG9ydD1UTFM-;nat=yes>,<sip:212.58.160.253:5061;transport=tls;r2=on;lr;ftag=2953e855;did=d9.e631;vsf=AAAAAAoLAQ4DAA4DAHlnYg5heGIAeXFodBhzYX8PBgoDAA1ESBEAXB4bHwEXdD1UTFM-;vst=AAAAAAMOAwEABwcEAwd3AnAcBAQAAwsAAAYKGwIDAwFAQ1NDBgQaDgAWcG9ydD1UTFM-;nat=yes>]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:74]: db_str2val(): converting INT [2]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING []
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING []
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING []
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING []
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING []
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING [INVITE]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_val.c:118]: db_str2val(): converting STRING [2]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 21 columns
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f53508b1c18
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[1] at 0x7f53508b2a60
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[2] at 0x7f5350b23588
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[3] at 0x7f53508affc0
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[4] at 0x7f5350b23a10
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[5] at 0x7f53508adab0
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[6] at 0x7f53508b1708
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[7] at 0x7f53508ae120
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[8] at 0x7f53508afa18
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[9] at 0x7f53508aee00
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[10] at 0x7f5350b21608
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[11] at 0x7f53508ad448
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[12] at 0x7f5350b229d8
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[13] at 0x7f53508b0640
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[14] at 0x7f53508af470
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[15] at 0x7f53508ae790
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[16] at 0x7f53508b2638
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[17] at 0x7f53508b2128
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[18] at 0x7f53508b11f8
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[19] at 0x7f5350b8ec78
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[20] at 0x7f5350bcb160
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:92]: db_free_columns(): freeing result names at 0x7f5350bcb3b0
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:97]: db_free_columns(): freeing result types at 0x7f5350b925f0
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:52]: db_free_rows(): freeing 1 rows
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_row.c:95]: db_free_row(): freeing row values at 0x7f5350aeb0a0
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:60]: db_free_rows(): freeing rows at 0x7f5350bcaee8
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [db_res.c:134]: db_free_result(): freeing result set at 0x7f5350b8ea98
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: topos [tps_storage.c:132]: tps_storage_lock_release(): tps lock release: 334
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:595]: parse_msg(): SIP Request:
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:597]: parse_msg():  method:  <INVITE>
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:599]: parse_msg():  uri:     <sip:KITS1.MSS.LIFE.COM:5060;transport=UDP>
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:601]: parse_msg():  version: <SIP/2.0>
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK-524287-1---c29d5f106e7a4975>; state=6
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=17
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:486]: parse_headers(): Via found, flags=2
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:488]: parse_headers(): this is the first via
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [receive.c:178]: receive_msg(): After parse_msg...
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [receive.c:221]: receive_msg(): preparing to run routing scripts...
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [select.c:412]: run_select(): Calling SELECT 0x7f53508ebed0
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_addr_spec.c:171]: parse_to_param(): DEBUG: add_param: tag=10000005923062
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_addr_spec.c:871]: parse_addr_spec(): end of header reached, state=29
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To> [73]; uri=[sip:380637304473 at 212.58.160.253:5061;transport=TLS]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:172]: get_hdr_field(): to body [<sip:380637304473 at 212.58.160.253:5061;transport=TLS>]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <3> <INVITE>
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=305
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_addr_spec.c:171]: parse_to_param(): DEBUG: add_param: tag=2953e855
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [parser/parse_addr_spec.c:871]: parse_addr_spec(): end of header reached, state=29
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: INFO: <script>: NATDETECT entered
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: siputils [checks.c:100]: has_totag(): totag found
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: rr [loose.c:89]: is_preloaded(): is_preloaded: No
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 18==11 && [KITS1.MSS.LIFE.COM] == [10.56.41.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 5060) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:583]: grep_sock_info(): checking advertise if host==us: 18==14 && [KITS1.MSS.LIFE.COM] == [212.58.162.215]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 18==11 && [KITS1.MSS.LIFE.COM] == [10.56.41.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5070 (advertise 5070) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 18==11 && [KITS1.MSS.LIFE.COM] == [10.56.42.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 18==11 && [KITS1.MSS.LIFE.COM] == [10.56.42.37]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 18==10 && [KITS1.MSS.LIFE.COM] == [10.1.23.30]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 18==11 && [KITS1.MSS.LIFE.COM] == [10.56.42.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 18==10 && [KITS1.MSS.LIFE.COM] == [10.1.23.30]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5161 (advertise 0) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 18==11 && [KITS1.MSS.LIFE.COM] == [10.56.41.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5061 (advertise 5061) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 18==11 && [KITS1.MSS.LIFE.COM] == [10.56.41.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5071 (advertise 0) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 18==11 && [KITS1.MSS.LIFE.COM] == [10.56.41.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5081 (advertise 5071) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 18==11 && [KITS1.MSS.LIFE.COM] == [10.56.41.30]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5061 (advertise 5061) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 18==11 && [KITS1.MSS.LIFE.COM] == [10.56.41.30]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5081 (advertise 5081) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [forward.c:412]: check_self(): host != me
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 14==11 && [212.58.160.253] == [10.56.41.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 5060) matches port 5061
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 14==11 && [212.58.160.253] == [10.56.41.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5070 (advertise 5070) matches port 5061
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 14==11 && [212.58.160.253] == [10.56.42.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5061
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 14==11 && [212.58.160.253] == [10.56.42.37]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5061
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 14==10 && [212.58.160.253] == [10.1.23.30]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5061
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 14==11 && [212.58.160.253] == [10.56.42.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5061
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 14==10 && [212.58.160.253] == [10.1.23.30]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5161 (advertise 0) matches port 5061
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 14==11 && [212.58.160.253] == [10.56.41.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5061 (advertise 5061) matches port 5061
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:583]: grep_sock_info(): checking advertise if host==us: 14==14 && [212.58.160.253] == [212.58.160.253]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: rr [loose.c:788]: after_loose(): Topmost route URI: 'sip:212.58.160.253:5061;transport=tls;r2=on;lr;ftag=2953e855;did=d9.e631;vsf=AAAAAAoLAQ4DAA4DAHlnYg5heGIAeXFodBhzYX8PBgoDAA1ESBEAXB4bHwEXdD1UTFM-;vst=AAAAAAMOAwEABwcEAwd3AnAcBAQAAwsAAAYKGwIDAwFAQ1NDBgQaDgAWcG9ydD1UTFM-;nat=yes' is me
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 11==11 && [10.56.42.33] == [10.56.41.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 5060) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:583]: grep_sock_info(): checking advertise if host==us: 11==14 && [10.56.42.33] == [212.58.162.215]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 11==11 && [10.56.42.33] == [10.56.41.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5070 (advertise 5070) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking if host==us: 11==11 && [10.56.42.33] == [10.56.42.33]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: rr [loose.c:884]: after_loose(): URI to be processed: 'sip:KITS1.MSS.LIFE.COM:5060;transport=UDP;lr'
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: rr [loose.c:893]: after_loose(): Next URI is a loose router
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: rr [rr_cb.c:95]: run_rr_callbacks(): callback id 1 entered with <transport=tls;r2=on;lr;ftag=2953e855;did=d9.e631;vsf=AAAAAAoLAQ4DAA4DAHlnYg5heGIAeXFodBhzYX8PBgoDAA1ESBEAXB4bHwEXdD1UTFM-;vst=AAAAAAMOAwEABwcEAwd3AnAcBAQAAwsAAAYKGwIDAwFAQ1NDBgQaDgAWcG9ydD1UTFM-;nat=yes>
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_handlers.c:1209]: dlg_onroute(): route param is 'd9.e631' (len=7)
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_hash.c:690]: dlg_lookup(): ref dlg 0x7f530e925af0 with 1 -> 3
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): dialog id=4974 found on entry 157
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_profile.c:530]: set_current_dialog(): setting current dialog [157:4974]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_hash.c:1122]: next_state_dlg(): dialog 0x7f530e925af0 changed from state 4 to state 4, due event 8 (ref 3)
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_timer.c:110]: insert_dialog_timer_unsafe(): inserting 0x7f530e925b50 for 118797440
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_hash.c:634]: dlg_update_contact(): same contact for leg[0] - [sip:380931701931 at 37.73.206.8:29300;transport=tls]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_hash.c:605]: dlg_update_cseq(): cseq of leg[0] is 3
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_handlers.c:1411]: dlg_onroute(): sequential request successfully processed
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: rr [record.c:918]: add_rr_param(): rr_param_buf=<;did=d9.e631>
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_hash.c:922]: dlg_unref_helper(): unref op on 0x7f530e925af0 with 1 from dlg_hash.c:940
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_hash.c:926]: dlg_unref_helper(): unref dlg 0x7f530e925af0 with 1 -> 2
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: rr [rr_cb.c:95]: run_rr_callbacks(): callback id 0 entered with <transport=tls;r2=on;lr;ftag=2953e855;did=d9.e631;vsf=AAAAAAoLAQ4DAA4DAHlnYg5heGIAeXFodBhzYX8PBgoDAA1ESBEAXB4bHwEXdD1UTFM-;vst=AAAAAAMOAwEABwcEAwd3AnAcBAQAAwsAAAYKGwIDAwFAQ1NDBgQaDgAWcG9ydD1UTFM-;nat=yes>
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: uac [replace.c:535]: restore_uri(): getting 'vsf' Route param
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: uac [replace.c:543]: restore_uri(): route param is 'AAAAAAoLAQ4DAA4DAHlnYg5heGIAeXFodBhzYX8PBgoDAA1ESBEAXB4bHwEXdD1UTFM-' (len=68)
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: rr [record.c:918]: add_rr_param(): rr_param_buf=<;did=d9.e631;vsf=AAAAAAoLAQ4DAA4DAHlnYg5heGIAeXFodBhzYX8PBgoDAA1ESBEAXB4bHwEXdD1UTFM->
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: uac [replace.c:645]: restore_uri(): decoded uris are: new=[sip:931701931 at TSNSIP.LIFE.COM:5060;user=phone] old=[sip:380931701931 at 212.58.160.253:5061;transport=TLS]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: uac [replace.c:535]: restore_uri(): getting 'vst' Route param
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: uac [replace.c:543]: restore_uri(): route param is 'AAAAAAMOAwEABwcEAwd3AnAcBAQAAwsAAAYKGwIDAwFAQ1NDBgQaDgAWcG9ydD1UTFM-' (len=68)
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: rr [record.c:918]: add_rr_param(): rr_param_buf=<;did=d9.e631;vsf=AAAAAAoLAQ4DAA4DAHlnYg5heGIAeXFodBhzYX8PBgoDAA1ESBEAXB4bHwEXdD1UTFM-;vst=AAAAAAMOAwEABwcEAwd3AnAcBAQAAwsAAAYKGwIDAwFAQ1NDBgQaDgAWcG9ydD1UTFM->
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: uac [replace.c:645]: restore_uri(): decoded uris are: new=[sip:0637304473 at 10.56.63.10:5060;user=phone] old=[sip:380637304473 at 212.58.160.253:5061;transport=TLS]
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: INFO: <script>: INVITE is forwarded statelessy Remove add Route
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: INFO: <script>:  Withindlg RELAY sip:KITS1.MSS.LIFE.COM:5060;transport=UDP
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: INFO: <script>: route RELAY entered 10.56.41.33:5061 212.58.160.253 fs=udp:10.56.42.33:5060 sndip=<null>
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=52 , global msg id=51 , T on entrance=(nil)
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=57554, isACK=0
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7f530e92a3b8, callback type 1, id 0 entered
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: siptrace [siptrace.c:1114]: trace_onreq_in(): trace off...
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7f530e92a3b8, callback type 1, id 0 entered
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_hash.c:690]: dlg_lookup(): ref dlg 0x7f530e925af0 with 1 -> 3
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_hash.c:692]: dlg_lookup(): dialog id=4974 found on entry 157
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_handlers.c:727]: dlg_onreq(): dialog added to tm callbacks
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_hash.c:922]: dlg_unref_helper(): unref op on 0x7f530e925af0 with 1 from dlg_hash.c:940
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: dialog [dlg_hash.c:926]: dlg_unref_helper(): unref dlg 0x7f530e925af0 with 1 -> 2
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7f530e92a3b8, callback type 1, id 0 entered
Apr 27 12:14:35 csbc-uat /usr/sbin/kamailio[10920]: DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 80f549253bc2b97c790a13ee3d0157a4