[OpenSIPS-Users] Registrar not saving received from Path header

Nathaniel L Keeling III keeling at akan-tech.com
Wed May 22 07:22:00 CEST 2013


Hello Bogdan,

Here is the output from the opensips log. I have also attached a snippet 
from the log file.

May 21 23:39:15   OpenSips[14397]: [ID 257313 local1.debug] 
DBG:registrar:save_aux: xXx - flags param is
May 21 23:39:15   OpenSips[14397]: [ID 154992 local1.debug] 
DBG:registrar:save_aux: xXx - flags bitmask is <0>

May 21 23:39:15   OpenSips[14397]: [ID 269964 local1.debug] 
DBG:registrar:pack_ci: xXx - flags are 0

Thanks

Nathaniel L Keeling

On 5/20/13 11:56 AM, Bogdan-Andrei Iancu wrote:
> Hello Nathaniel,
>
> See the attached patch - it logs more from the part where the params 
> are handled .
>
> Regards,
> Bogdan-Andrei Iancu
> OpenSIPS Founder and Developer
> http://www.opensips-solutions.com
>
> On 05/18/2013 09:33 AM, Nathaniel L Keeling III wrote:
>> Hello Bogdan,
>>
>> Here are snippets from my script. I only have one place where I 
>> execute the save function. Just wondering, could it be truncating a byte?
>>
>> modparam("usrloc", "nat_bflag", 10)
>> modparam("usrloc", "use_domain", 1)
>> modparam("usrloc", "db_mode", 3)
>> modparam("usrloc", "db_url",
>> "postgres://opensips:opensipsrw@ama.akan.net/opensips181t")
>> modparam("registrar", "tcp_persistent_flag", 7)
>> modparam("registrar", "received_avp", "$avp(received_nh)")
>>
>>
>>     xlog("SAVING THE SUBSCRIBER INTO THE LOCATION TABLE 
>> ....................................................");
>>     if (!save("location","p1"))
>>     {
>>         xlog("L_ERR", "ERR:callerid:$ci|end|System error trying to 
>> save Register's request location");
>>         sl_reply_error();
>>     }
>>
>>     xlog("L_NOTICE", "NOTICE:callerid:$ci|end|The subscriber has 
>> successfully registered with Akan Voice");
>>     exit;
>>
>> Thanks
>>
>> Nathaniel
>>
>> On 5/17/13 6:07 AM, Bogdan-Andrei Iancu wrote:
>>> Hello Nathaniel,
>>>
>>> That is odd.....it's like you do not set the "p1" flag ....
>>>
>>> I tested and I with "p1" flag I get:
>>>     May 17 14:05:03 [7944] DBG:registrar:pack_ci: xXx - flags are 10
>>>
>>> Are you sure your script gets to the right save() ??
>>>
>>> Regards,
>>> Bogdan-Andrei Iancu
>>> OpenSIPS Founder and Developer
>>> http://www.opensips-solutions.com
>>>
>>> On 05/17/2013 09:37 AM, Nathaniel L Keeling III wrote:
>>>> Hello Bogdan,
>>>>
>>>> I added the patch and here is what I found: "OpenSips[4378]: [ID 
>>>> 269964 local1.debug] DBG:registrar:pack_ci: xXx - flags are 0". I 
>>>> have also included the log file.
>>>>
>>>> Thanks
>>>>
>>>> Nathaniel Keeling
>>>>
>>>> On 5/16/13 3:47 AM, Bogdan-Andrei Iancu wrote:
>>>>> Hello Nathaniel,
>>>>>
>>>>> Attached is an extended patch - remove the old one and apply this 
>>>>> one. Again look for any xXx logs .
>>>>>
>>>>> Regards,
>>>>> Bogdan-Andrei Iancu
>>>>> OpenSIPS Founder and Developer
>>>>> http://www.opensips-solutions.com
>>>>>
>>>>> On 05/14/2013 02:47 PM, Nathaniel L Keeling III wrote:
>>>>>> Hello Bogdan,
>>>>>>
>>>>>> here is the output from opensips's og file of the save() with the 
>>>>>> patch and the code snippet from the opensips.cfg. I did not see 
>>>>>> any ant logs with "xXx". Also,I have usrloc's db_mode set to 3.
>>>>>>
>>>>>>     xlog("SAVING THE SUBSCRIBER INTO THE LOCATION TABLE 
>>>>>> ....................................................");
>>>>>>     if (!save("location","p1"))
>>>>>>     {
>>>>>>         xlog("L_ERR", "ERR:callerid:$ci|end|System error trying 
>>>>>> to save Register's request location");
>>>>>>         sl_reply_error();
>>>>>>     }
>>>>>>     xlog("L_NOTICE", "NOTICE:callerid:$ci|end|The subscriber has 
>>>>>> successfully registered with Akan Voice");
>>>>>>     exit; 
>>>>
>>>>
>>>> _______________________________________________
>>>> Users mailing list
>>>> Users at lists.opensips.org
>>>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>>
>>>
>>> _______________________________________________
>>> Users mailing list
>>> Users at lists.opensips.org
>>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>
>>
>> _______________________________________________
>> Users mailing list
>> Users at lists.opensips.org
>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opensips.org/pipermail/users/attachments/20130522/f713becb/attachment-0001.htm>
-------------- next part --------------
May 21 23:39:15   OpenSips[14397]: [ID 197553 local1.error] SAVING THE SUBSCRIBER INTO THE LOCATION TABLE ....................................................
May 21 23:39:15   OpenSips[14397]: [ID 257313 local1.debug] DBG:registrar:save_aux: xXx - flags param is  
May 21 23:39:15   OpenSips[14397]: [ID 154992 local1.debug] DBG:registrar:save_aux: xXx - flags bitmask is <0> 
May 21 23:39:15   OpenSips[14397]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=ffffffffffffffff
May 21 23:39:15   OpenSips[14397]: [ID 421386 local1.debug] DBG:core:parse_uri: parsed uri:
May 21 23:39:15    type=1 user=<nkeeling>(8)
May 21 23:39:15    passwd=<>(0)
May 21 23:39:15    host=<akanvoice.com>(13)
May 21 23:39:15    port=<>(0): 0
May 21 23:39:15    params=<>(0)
May 21 23:39:15    headers=<>(0)
May 21 23:39:15   OpenSips[14397]: [ID 883594 local1.debug] DBG:core:parse_uri:  uri params:
May 21 23:39:15      transport=<>, val=<>, proto=0
May 21 23:39:15   OpenSips[14397]: [ID 996803 local1.debug] DBG:core:parse_uri:    user-param=<>, val=<>
May 21 23:39:15   OpenSips[14397]: [ID 784403 local1.debug] DBG:core:parse_uri:    method=<>, val=<>
May 21 23:39:15   OpenSips[14397]: [ID 185236 local1.debug] DBG:core:parse_uri:    ttl=<>, val=<>
May 21 23:39:15   OpenSips[14397]: [ID 628781 local1.debug] DBG:core:parse_uri:    maddr=<>, val=<>
May 21 23:39:15   OpenSips[14397]: [ID 949013 local1.debug] DBG:core:parse_uri:    lr=<>, val=<>
May 21 23:39:15   OpenSips[14397]: [ID 595631 local1.debug] DBG:core:parse_uri:    r2=<>, val=<>
May 21 23:39:15   OpenSips[14397]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 8 chars, out: 8 chars
May 21 23:39:15   OpenSips[14397]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 13 chars, out: 13 chars
May 21 23:39:15   OpenSips[14397]: [ID 181770 local1.debug] DBG:db_postgres:db_postgres_submit_query: 100391220 PQsendQuery(select contact,expires,q,callid,cseq,flags,cflags,user_agent,received,path,socket,methods,last_modified,sip_instance from location where username='nkeeling' AND domain='akanvoice.com' order by q)
May 21 23:39:15   OpenSips[14397]: [ID 529718 local1.debug] DBG:core:db_new_result: allocate 48 bytes for result set at 1003960d0
May 21 23:39:15   OpenSips[14397]: [ID 911073 local1.debug] DBG:db_postgres:db_postgres_store_result: 100391220 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(100782900)
May 21 23:39:15   OpenSips[14397]: [ID 460951 local1.debug] DBG:db_postgres:db_postgres_get_columns: 14 columns returned from the query
May 21 23:39:15   OpenSips[14397]: [ID 586195 local1.debug] DBG:core:db_allocate_columns: allocate 392 bytes for result columns at 100396358
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(1003963c8)[0]=[contact]
May 21 23:39:15   OpenSips[14397]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(1003963d8)[1]=[expires]
May 21 23:39:15   OpenSips[14397]: [ID 588722 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_DATETIME result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(1003963e8)[2]=[q]
May 21 23:39:15   OpenSips[14397]: [ID 656759 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_DOUBLE result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(1003963f8)[3]=[callid]
May 21 23:39:15   OpenSips[14397]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396408)[4]=[cseq]
May 21 23:39:15   OpenSips[14397]: [ID 499560 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_INT result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396418)[5]=[flags]
May 21 23:39:15   OpenSips[14397]: [ID 499560 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_INT result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396428)[6]=[cflags]
May 21 23:39:15   OpenSips[14397]: [ID 499560 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_INT result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396438)[7]=[user_agent]
May 21 23:39:15   OpenSips[14397]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396448)[8]=[received]
May 21 23:39:15   OpenSips[14397]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396458)[9]=[path]
May 21 23:39:15   OpenSips[14397]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396468)[10]=[socket]
May 21 23:39:15   OpenSips[14397]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396478)[11]=[methods]
May 21 23:39:15   OpenSips[14397]: [ID 499560 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_INT result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396488)[12]=[last_modified]
May 21 23:39:15   OpenSips[14397]: [ID 588722 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_DATETIME result type
May 21 23:39:15   OpenSips[14397]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396498)[13]=[sip_instance]
May 21 23:39:15   OpenSips[14397]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 21 23:39:15   OpenSips[14397]: [ID 991251 local1.debug] DBG:db_postgres:db_postgres_convert_rows: no rows returned from the query
May 21 23:39:15   OpenSips[14397]: [ID 198008 local1.debug] DBG:db_postgres:free_query: PQclear(100782900) result set
May 21 23:39:15   OpenSips[14397]: [ID 333765 local1.debug] DBG:usrloc:db_load_urecord: aor nkeeling at akanvoice.com not found in table location
May 21 23:39:15   OpenSips[14397]: [ID 321192 local1.debug] DBG:core:db_free_columns: freeing result columns at 100396358
May 21 23:39:15   OpenSips[14397]: [ID 670447 local1.debug] DBG:core:db_free_rows: freeing 0 rows
May 21 23:39:15   OpenSips[14397]: [ID 921065 local1.debug] DBG:core:db_free_result: freeing result set at 1003960d0
May 21 23:39:15   OpenSips[14397]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=8000000
May 21 23:39:15   OpenSips[14397]: [ID 269964 local1.debug] DBG:registrar:pack_ci: xXx - flags are 0 
May 21 23:39:15   OpenSips[14397]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=ffffffffffffffff
May 21 23:39:15   OpenSips[14397]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 8 chars, out: 8 chars
May 21 23:39:15   OpenSips[14397]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 48 chars, out: 48 chars
May 21 23:39:15   OpenSips[14397]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 28 chars, out: 28 chars
May 21 23:39:15   OpenSips[14397]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 25 chars, out: 25 chars
May 21 23:39:15   OpenSips[14397]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 23 chars, out: 23 chars
May 21 23:39:15   last message repeated 1 time
May 21 23:39:15   OpenSips[14397]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 13 chars, out: 13 chars
May 21 23:39:15   OpenSips[14397]: [ID 181770 local1.debug] DBG:db_postgres:db_postgres_submit_query: 100391220 PQsendQuery(insert into location (username,contact,expires,q,callid,cseq,flags,cflags,user_agent,received,path,socket,methods,last_modified,sip_instance,domain ) values ('nkeeling','sip:nkeeling at 100.228.192.160:59823;transport=udp','2013-05-22 00:39:15',-1.00     ,'304993846543 at 100.228.192.160',2,0,0,'Sipdroid/3.0 beta/LG-P999','sip:208.54.44.140:34503',NULL,'udp:209.252.110.48:5060',NULL,'2013-05-21 23:39:15',NULL,'akanvoice.com'))
May 21 23:39:15   OpenSips[14397]: [ID 529718 local1.debug] DBG:core:db_new_result: allocate 48 bytes for result set at 1003960d0
May 21 23:39:15   OpenSips[14397]: [ID 911073 local1.debug] DBG:db_postgres:db_postgres_store_result: 100391220 PQresultStatus(PGRES_COMMAND_OK) PQgetResult(1007829e0)
May 21 23:39:15   OpenSips[14397]: [ID 198008 local1.debug] DBG:db_postgres:free_query: PQclear(1007829e0) result set
May 21 23:39:15   OpenSips[14397]: [ID 670447 local1.debug] DBG:core:db_free_rows: freeing 0 rows
May 21 23:39:15   OpenSips[14397]: [ID 921065 local1.debug] DBG:core:db_free_result: freeing result set at 1003960d0
May 21 23:39:15   OpenSips[14397]: [ID 866151 local1.debug] DBG:registrar:build_contact: created Contact HF: Contact: <sip:nkeeling at 100.228.192.160:59823;transport=udp>;expires=3600;received="sip:208.54.44.140:34503"^M
May 21 23:39:15   OpenSips[14397]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=ffffffffffffffff
May 21 23:39:15   OpenSips[14397]: [ID 153352 local1.debug] DBG:core:pv_printf: final buffer length 107
May 21 23:39:15   OpenSips[14397]: [ID 197553 local1.notice] NOTICE:callerid:304993846543 at 100.228.192.160|end|The subscriber has successfully registered with Akan Voice


More information about the Users mailing list