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

Nathaniel L Keeling III keeling at akan-tech.com
Fri May 17 08:37:39 CEST 2013


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; 

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opensips.org/pipermail/users/attachments/20130517/f101c42d/attachment-0001.htm>
-------------- next part --------------
May 16 23:35:53   OpenSips[4378]: [ID 292666 local1.debug] DBG:core:parse_msg: SIP Request:
May 16 23:35:53   OpenSips[4378]: [ID 776402 local1.debug] DBG:core:parse_msg:  method:  <REGISTER>
May 16 23:35:53   OpenSips[4378]: [ID 700387 local1.debug] DBG:core:parse_msg:  uri:     <sip:akanvoice.com>
May 16 23:35:53   OpenSips[4378]: [ID 641661 local1.debug] DBG:core:parse_msg:  version: <SIP/2.0>
May 16 23:35:53   OpenSips[4378]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=2
May 16 23:35:53   OpenSips[4378]: [ID 202288 local1.debug] DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK69354>; state=16
May 16 23:35:53   OpenSips[4378]: [ID 218084 local1.debug] DBG:core:parse_via: end of header reached, state=5
May 16 23:35:53   OpenSips[4378]: [ID 636936 local1.debug] DBG:core:parse_headers: via found, flags=2
May 16 23:35:53   OpenSips[4378]: [ID 481110 local1.debug] DBG:core:parse_headers: this is the first via
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 1, name=<Via>, body=<SIP/2.0/UDP 209.252.110.38:5060;branch=z9hG4bK69354>
May 16 23:35:53   OpenSips[4378]: [ID 499462 local1.debug] DBG:core:parse_msg:  first  via: <SIP/2.0/UDP> <209.252.110.38:5060(5060)>
May 16 23:35:53   OpenSips[4378]: [ID 573780 local1.debug] DBG:core:parse_msg: ;<branch=z9hG4bK69354>
May 16 23:35:53   OpenSips[4378]: [ID 937246 local1.debug] DBG:core:parse_msg: 
May 16 23:35:53   OpenSips[4378]: [ID 979351 local1.debug] DBG:core:parse_msg: exiting
May 16 23:35:53   OpenSips[4378]: [ID 911547 local1.debug] DBG:core:receive_msg: After parse_msg...
May 16 23:35:53   OpenSips[4378]: [ID 451678 local1.debug] DBG:core:receive_msg: preparing to run routing scripts...
May 16 23:35:53   OpenSips[4378]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=40
May 16 23:35:53   OpenSips[4378]: [ID 202288 local1.debug] DBG:core:parse_via_param: found param type 234, <received> = <208.54.44.253>; state=6
May 16 23:35:53   OpenSips[4378]: [ID 202288 local1.debug] DBG:core:parse_via_param: found param type 235, <rport> = <44494>; state=6
May 16 23:35:53   OpenSips[4378]: [ID 202288 local1.debug] DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK69354>; state=16
May 16 23:35:53   OpenSips[4378]: [ID 218084 local1.debug] DBG:core:parse_via: end of header reached, state=5
May 16 23:35:53   OpenSips[4378]: [ID 636936 local1.debug] DBG:core:parse_headers: via found, flags=40
May 16 23:35:53   OpenSips[4378]: [ID 903840 local1.debug] DBG:core:parse_headers: parse_headers: this is the second via
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 1, name=<Via>, body=<SIP/2.0/UDP 100.229.65.174:43669;received=208.54.44.253;rport=44494;branch=z9hG4bK69354>
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 8, name=<Max-Forwards>, body=<30>
May 16 23:35:53   OpenSips[4378]: [ID 218084 local1.debug] DBG:core:parse_to: end of header reached, state=10
May 16 23:35:53   OpenSips[4378]: [ID 841317 local1.debug] DBG:core:parse_to: display={}, ruri={sip:nkeeling at akanvoice.com}
May 16 23:35:53   OpenSips[4378]: [ID 993225 local1.debug] DBG:core:get_hdr_field: <To> [30]; uri=[sip:nkeeling at akanvoice.com] 
May 16 23:35:53   OpenSips[4378]: [ID 159376 local1.debug] DBG:core:get_hdr_field: to body [<sip:nkeeling at akanvoice.com>^M
May 16 23:35:53   ]
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 3, name=<To>, body=<<sip:nkeeling at akanvoice.com>>
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 4, name=<From>, body=<<sip:nkeeling at akanvoice.com>;tag=z9hG4bK97036383>
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 6, name=<Call-ID>, body=<548361087116 at 100.229.65.174>
May 16 23:35:53   OpenSips[4378]: [ID 421386 local1.debug] DBG:core:parse_uri: parsed uri:
May 16 23:35:53    type=1 user=<>(0)
May 16 23:35:53    passwd=<>(0)
May 16 23:35:53    host=<akanvoice.com>(13)
May 16 23:35:53    port=<>(0): 0
May 16 23:35:53    params=<>(0)
May 16 23:35:53    headers=<>(0)
May 16 23:35:53   OpenSips[4378]: [ID 883594 local1.debug] DBG:core:parse_uri:  uri params:
May 16 23:35:53      transport=<>, val=<>, proto=0
May 16 23:35:53   OpenSips[4378]: [ID 996803 local1.debug] DBG:core:parse_uri:    user-param=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 784403 local1.debug] DBG:core:parse_uri:    method=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 185236 local1.debug] DBG:core:parse_uri:    ttl=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 628781 local1.debug] DBG:core:parse_uri:    maddr=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 949013 local1.debug] DBG:core:parse_uri:    lr=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 595631 local1.debug] DBG:core:parse_uri:    r2=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 153352 local1.debug] DBG:core:pv_printf: final buffer length 95
May 16 23:35:53   OpenSips[4378]: [ID 197553 local1.info] INFO:callerid:548361087116 at 100.229.65.174|start|recieved udp request REGISTER sip:akanvoice.com
May 16 23:35:53   OpenSips[4378]: [ID 153352 local1.debug] DBG:core:pv_printf: final buffer length 83
May 16 23:35:53   OpenSips[4378]: [ID 197553 local1.info] INFO:callerid:548361087116 at 100.229.65.174|log|source IP address 209.252.110.38:5060
May 16 23:35:53   OpenSips[4378]: [ID 421386 local1.debug] DBG:core:parse_uri: parsed uri:
May 16 23:35:53    type=1 user=<>(0)
May 16 23:35:53    passwd=<>(0)
May 16 23:35:53    host=<akanvoice.com>(13)
May 16 23:35:53    port=<>(0): 0
May 16 23:35:53    params=<>(0)
May 16 23:35:53    headers=<>(0)
May 16 23:35:53   OpenSips[4378]: [ID 883594 local1.debug] DBG:core:parse_uri:  uri params:
May 16 23:35:53      transport=<>, val=<>, proto=0
May 16 23:35:53   OpenSips[4378]: [ID 996803 local1.debug] DBG:core:parse_uri:    user-param=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 784403 local1.debug] DBG:core:parse_uri:    method=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 185236 local1.debug] DBG:core:parse_uri:    ttl=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 628781 local1.debug] DBG:core:parse_uri:    maddr=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 949013 local1.debug] DBG:core:parse_uri:    lr=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 595631 local1.debug] DBG:core:parse_uri:    r2=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 153352 local1.debug] DBG:core:pv_printf: final buffer length 75
May 16 23:35:53   OpenSips[4378]: [ID 197553 local1.info] INFO:callerid:548361087116 at 100.229.65.174|log|request uri sip:akanvoice.com
May 16 23:35:53   OpenSips[4378]: [ID 858957 local1.debug] DBG:core:parse_to_param: tag=z9hG4bK97036383
May 16 23:35:53   OpenSips[4378]: [ID 218084 local1.debug] DBG:core:parse_to: end of header reached, state=29
May 16 23:35:53   OpenSips[4378]: [ID 841317 local1.debug] DBG:core:parse_to: display={}, ruri={sip:nkeeling at akanvoice.com}
May 16 23:35:53   OpenSips[4378]: [ID 153352 local1.debug] DBG:core:pv_printf: final buffer length 77
May 16 23:35:53   OpenSips[4378]: [ID 197553 local1.info] INFO:callerid:548361087116 at 100.229.65.174|log|from sip:nkeeling at akanvoice.com
May 16 23:35:53   OpenSips[4378]: [ID 153352 local1.debug] DBG:core:pv_printf: final buffer length 75
May 16 23:35:53   OpenSips[4378]: [ID 197553 local1.info] INFO:callerid:548361087116 at 100.229.65.174|log|to sip:nkeeling at akanvoice.com
May 16 23:35:53   OpenSips[4378]: [ID 153352 local1.debug] DBG:core:pv_printf: final buffer length 82
May 16 23:35:53   OpenSips[4378]: [ID 197553 local1.info] INFO:callerid:548361087116 at 100.229.65.174|log|Request sent from internal SIP proxy
May 16 23:35:53   OpenSips[4378]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=ffffffffffffffff
May 16 23:35:53   OpenSips[4378]: [ID 601073 local1.debug] DBG:core:get_hdr_field: cseq <CSeq>: <2> <REGISTER>
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 5, name=<CSeq>, body=<2 REGISTER>
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 7, name=<Contact>, body=<<sip:nkeeling at 100.229.65.174:43669;transport=udp>;received="sip:208.54.44.253:44494">
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 15, name=<Expires>, body=<3600>
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 27, name=<User-Agent>, body=<Sipdroid/3.0 beta/LG-P999>
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 14, name=<Authorization>, body=<Digest username="nkeeling at akanvoice.com", realm="akanvoice.com", nonce="5195b3c600000025e93852ac4d1df841f2f3a54e4f26677b", uri="sip:akanvoice.com", response="3f4b80852004e57d7c805a6c7b7dc5af">
May 16 23:35:53   OpenSips[4378]: [ID 457772 local1.debug] DBG:core:get_hdr_field: content_length=0
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 13, name=<Content-Length>, body=<0>
May 16 23:35:53   OpenSips[4378]: [ID 994387 local1.debug] DBG:core:parse_headers: header field type 11, name=<Path>, body=<<sip:209.252.110.38;lr;received=sip:208.54.44.253:44494>>
May 16 23:35:53   OpenSips[4378]: [ID 183338 local1.debug] DBG:core:get_hdr_field: found end of header
May 16 23:35:53   OpenSips[4378]: [ID 758823 local1.debug] DBG:core:parse_params: Parsing params for:[received="sip:208.54.44.253:44494"]
May 16 23:35:53   OpenSips[4378]: [ID 800532 local1.debug] DBG:sipmsgops:sip_validate_hdrs: duplicate header 'Content-Length'
May 16 23:35:53   OpenSips[4378]: [ID 289059 local1.debug] DBG:core:check_ip_address: params 209.252.110.38, 209.252.110.38, 0
May 16 23:35:53   OpenSips[4378]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=ffffffffffffffff
May 16 23:35:53   OpenSips[4378]: [ID 421386 local1.debug] DBG:core:parse_uri: parsed uri:
May 16 23:35:53    type=1 user=<nkeeling>(8)
May 16 23:35:53    passwd=<>(0)
May 16 23:35:53    host=<100.229.65.174>(14)
May 16 23:35:53    port=<43669>(5): 43669
May 16 23:35:53    params=<transport=udp>(13)
May 16 23:35:53    headers=<>(0)
May 16 23:35:53   OpenSips[4378]: [ID 883594 local1.debug] DBG:core:parse_uri:  uri params:
May 16 23:35:53      transport=<transport=udp>, val=<udp>, proto=1
May 16 23:35:53   OpenSips[4378]: [ID 996803 local1.debug] DBG:core:parse_uri:    user-param=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 784403 local1.debug] DBG:core:parse_uri:    method=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 185236 local1.debug] DBG:core:parse_uri:    ttl=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 628781 local1.debug] DBG:core:parse_uri:    maddr=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 949013 local1.debug] DBG:core:parse_uri:    lr=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 595631 local1.debug] DBG:core:parse_uri:    r2=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 138385 local1.debug] DBG:uri:has_totag: no totag
May 16 23:35:53   OpenSips[4378]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=78
May 16 23:35:53   OpenSips[4378]: [ID 805717 local1.debug] DBG:tm:t_lookup_request: start searching: hash=2408, isACK=0
May 16 23:35:53   OpenSips[4378]: [ID 814815 local1.debug] DBG:tm:matching_3261: RFC3261 transaction matching failed
May 16 23:35:53   OpenSips[4378]: [ID 533934 local1.debug] DBG:tm:t_lookup_request: no transaction found
May 16 23:35:53   OpenSips[4378]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=200
May 16 23:35:53   OpenSips[4378]: [ID 165445 local1.debug] DBG:rr:find_first_route: No Route headers found
May 16 23:35:53   OpenSips[4378]: [ID 648121 local1.debug] DBG:rr:loose_route: There is no Route HF
May 16 23:35:53   OpenSips[4378]: [ID 421386 local1.debug] DBG:core:parse_uri: parsed uri:
May 16 23:35:53    type=1 user=<nkeeling>(8)
May 16 23:35:53    passwd=<>(0)
May 16 23:35:53    host=<akanvoice.com>(13)
May 16 23:35:53    port=<>(0): 0
May 16 23:35:53    params=<>(0)
May 16 23:35:53    headers=<>(0)
May 16 23:35:53   OpenSips[4378]: [ID 883594 local1.debug] DBG:core:parse_uri:  uri params:
May 16 23:35:53      transport=<>, val=<>, proto=0
May 16 23:35:53   OpenSips[4378]: [ID 996803 local1.debug] DBG:core:parse_uri:    user-param=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 784403 local1.debug] DBG:core:parse_uri:    method=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 185236 local1.debug] DBG:core:parse_uri:    ttl=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 628781 local1.debug] DBG:core:parse_uri:    maddr=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 949013 local1.debug] DBG:core:parse_uri:    lr=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 595631 local1.debug] DBG:core:parse_uri:    r2=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 552807 local1.debug] DBG:auth:check_nonce: comparing [5195b3c600000025e93852ac4d1df841f2f3a54e4f26677b] and [5195b3c600000025e93852ac4d1df841f2f3a54e4f26677b]
May 16 23:35:53   OpenSips[4378]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 8 chars, out: 8 chars
May 16 23:35:53   OpenSips[4378]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 13 chars, out: 13 chars
May 16 23:35:53   OpenSips[4378]: [ID 181770 local1.debug] DBG:db_postgres:db_postgres_submit_query: 100392fe0 PQsendQuery(select password from subscriber where username='nkeeling' AND domain='akanvoice.com')
May 16 23:35:53   OpenSips[4378]: [ID 529718 local1.debug] DBG:core:db_new_result: allocate 48 bytes for result set at 100395bb8
May 16 23:35:53   OpenSips[4378]: [ID 911073 local1.debug] DBG:db_postgres:db_postgres_store_result: 100392fe0 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(1007826d0)
May 16 23:35:53   OpenSips[4378]: [ID 460951 local1.debug] DBG:db_postgres:db_postgres_get_columns: 1 columns returned from the query
May 16 23:35:53   OpenSips[4378]: [ID 586195 local1.debug] DBG:core:db_allocate_columns: allocate 28 bytes for result columns at 100395a20
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100395a28)[0]=[password]
May 16 23:35:53   OpenSips[4378]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 16 23:35:53   OpenSips[4378]: [ID 216771 local1.debug] DBG:db_postgres:db_postgres_convert_rows: allocate for 1 columns 8 bytes in row buffer at 10038ab20
May 16 23:35:53   OpenSips[4378]: [ID 996937 local1.debug] DBG:core:db_allocate_rows: allocate 48 bytes for result rows and values at 100395ad0
May 16 23:35:53   OpenSips[4378]: [ID 499574 local1.debug] DBG:db_postgres:db_postgres_convert_rows: PQgetvalue(100392fe0,0,0)=[03nlk59]
May 16 23:35:53   OpenSips[4378]: [ID 661946 local1.debug] DBG:db_postgres:db_postgres_convert_rows: allocated 7 bytes for row_buf[0] at 10038a350
May 16 23:35:53   OpenSips[4378]: [ID 932285 local1.debug] DBG:db_postgres:db_postgres_convert_rows: [0][0] Column[password]=[03nlk59]
May 16 23:35:53   OpenSips[4378]: [ID 766407 local1.debug] DBG:db_postgres:db_postgres_str2val: converting STRING [03nlk59]
May 16 23:35:53   OpenSips[4378]: [ID 304422 local1.debug] DBG:db_postgres:db_postgres_convert_rows: freeing row buffer at 10038ab20
May 16 23:35:53   OpenSips[4378]: [ID 198008 local1.debug] DBG:db_postgres:free_query: PQclear(1007826d0) result set
May 16 23:35:53   OpenSips[4378]: [ID 927175 local1.debug] DBG:auth_db:get_ha1: HA1 string calculated: e760ef86426f7e7c4a322daa6cbcf63c
May 16 23:35:53   OpenSips[4378]: [ID 857137 local1.debug] DBG:auth:check_response: our result = '3f4b80852004e57d7c805a6c7b7dc5af'
May 16 23:35:53   OpenSips[4378]: [ID 289950 local1.debug] DBG:auth:check_response: authorization is OK
May 16 23:35:53   OpenSips[4378]: [ID 354785 local1.debug] DBG:auth:post_auth: nonce index= 37
May 16 23:35:53   OpenSips[4378]: [ID 321192 local1.debug] DBG:core:db_free_columns: freeing result columns at 100395a20
May 16 23:35:53   OpenSips[4378]: [ID 670447 local1.debug] DBG:core:db_free_rows: freeing 1 rows
May 16 23:35:53   OpenSips[4378]: [ID 560237 local1.debug] DBG:core:db_free_row: freeing row values at 100395ae0
May 16 23:35:53   OpenSips[4378]: [ID 642860 local1.debug] DBG:core:db_free_row: free VAL_STRING[0] '03nlk59' at 10038a350
May 16 23:35:53   OpenSips[4378]: [ID 486703 local1.debug] DBG:core:db_free_rows: freeing rows at 100395ad0
May 16 23:35:53   OpenSips[4378]: [ID 921065 local1.debug] DBG:core:db_free_result: freeing result set at 100395bb8
May 16 23:35:53   OpenSips[4378]: [ID 694823 local1.debug] DBG:uri:check_username: Digest username and URI username match
May 16 23:35:53   OpenSips[4378]: [ID 153352 local1.debug] DBG:core:pv_printf: final buffer length 98
May 16 23:35:53   OpenSips[4378]: [ID 197553 local1.error] SAVING THE SUBSCRIBER INTO THE LOCATION TABLE ....................................................
May 16 23:35:53   OpenSips[4378]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=ffffffffffffffff
May 16 23:35:53   OpenSips[4378]: [ID 421386 local1.debug] DBG:core:parse_uri: parsed uri:
May 16 23:35:53    type=1 user=<nkeeling>(8)
May 16 23:35:53    passwd=<>(0)
May 16 23:35:53    host=<akanvoice.com>(13)
May 16 23:35:53    port=<>(0): 0
May 16 23:35:53    params=<>(0)
May 16 23:35:53    headers=<>(0)
May 16 23:35:53   OpenSips[4378]: [ID 883594 local1.debug] DBG:core:parse_uri:  uri params:
May 16 23:35:53      transport=<>, val=<>, proto=0
May 16 23:35:53   OpenSips[4378]: [ID 996803 local1.debug] DBG:core:parse_uri:    user-param=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 784403 local1.debug] DBG:core:parse_uri:    method=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 185236 local1.debug] DBG:core:parse_uri:    ttl=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 628781 local1.debug] DBG:core:parse_uri:    maddr=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 949013 local1.debug] DBG:core:parse_uri:    lr=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 595631 local1.debug] DBG:core:parse_uri:    r2=<>, val=<>
May 16 23:35:53   OpenSips[4378]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 8 chars, out: 8 chars
May 16 23:35:53   OpenSips[4378]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 13 chars, out: 13 chars
May 16 23:35:53   OpenSips[4378]: [ID 181770 local1.debug] DBG:db_postgres:db_postgres_submit_query: 100390e28 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 16 23:35:53   OpenSips[4378]: [ID 529718 local1.debug] DBG:core:db_new_result: allocate 48 bytes for result set at 100395bb8
May 16 23:35:53   OpenSips[4378]: [ID 911073 local1.debug] DBG:db_postgres:db_postgres_store_result: 100390e28 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(100782740)
May 16 23:35:53   OpenSips[4378]: [ID 460951 local1.debug] DBG:db_postgres:db_postgres_get_columns: 14 columns returned from the query
May 16 23:35:53   OpenSips[4378]: [ID 586195 local1.debug] DBG:core:db_allocate_columns: allocate 392 bytes for result columns at 100396628
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396698)[0]=[contact]
May 16 23:35:53   OpenSips[4378]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(1003966a8)[1]=[expires]
May 16 23:35:53   OpenSips[4378]: [ID 588722 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_DATETIME result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(1003966b8)[2]=[q]
May 16 23:35:53   OpenSips[4378]: [ID 656759 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_DOUBLE result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(1003966c8)[3]=[callid]
May 16 23:35:53   OpenSips[4378]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(1003966d8)[4]=[cseq]
May 16 23:35:53   OpenSips[4378]: [ID 499560 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_INT result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(1003966e8)[5]=[flags]
May 16 23:35:53   OpenSips[4378]: [ID 499560 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_INT result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(1003966f8)[6]=[cflags]
May 16 23:35:53   OpenSips[4378]: [ID 499560 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_INT result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396708)[7]=[user_agent]
May 16 23:35:53   OpenSips[4378]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396718)[8]=[received]
May 16 23:35:53   OpenSips[4378]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396728)[9]=[path]
May 16 23:35:53   OpenSips[4378]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396738)[10]=[socket]
May 16 23:35:53   OpenSips[4378]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396748)[11]=[methods]
May 16 23:35:53   OpenSips[4378]: [ID 499560 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_INT result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396758)[12]=[last_modified]
May 16 23:35:53   OpenSips[4378]: [ID 588722 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_DATETIME result type
May 16 23:35:53   OpenSips[4378]: [ID 412218 local1.debug] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100396768)[13]=[sip_instance]
May 16 23:35:53   OpenSips[4378]: [ID 995569 local1.debug] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 16 23:35:53   OpenSips[4378]: [ID 991251 local1.debug] DBG:db_postgres:db_postgres_convert_rows: no rows returned from the query
May 16 23:35:53   OpenSips[4378]: [ID 198008 local1.debug] DBG:db_postgres:free_query: PQclear(100782740) result set
May 16 23:35:53   OpenSips[4378]: [ID 333765 local1.debug] DBG:usrloc:db_load_urecord: aor nkeeling at akanvoice.com not found in table location
May 16 23:35:53   OpenSips[4378]: [ID 321192 local1.debug] DBG:core:db_free_columns: freeing result columns at 100396628
May 16 23:35:53   OpenSips[4378]: [ID 670447 local1.debug] DBG:core:db_free_rows: freeing 0 rows
May 16 23:35:53   OpenSips[4378]: [ID 921065 local1.debug] DBG:core:db_free_result: freeing result set at 100395bb8
May 16 23:35:53   OpenSips[4378]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=8000000
May 16 23:35:53   OpenSips[4378]: [ID 269964 local1.debug] DBG:registrar:pack_ci: xXx - flags are 0 
May 16 23:35:53   OpenSips[4378]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=ffffffffffffffff
May 16 23:35:53   OpenSips[4378]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 8 chars, out: 8 chars
May 16 23:35:53   OpenSips[4378]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 47 chars, out: 47 chars
May 16 23:35:53   OpenSips[4378]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 27 chars, out: 27 chars
May 16 23:35:53   OpenSips[4378]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 25 chars, out: 25 chars
May 16 23:35:53   OpenSips[4378]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 23 chars, out: 23 chars
May 16 23:35:53   last message repeated 1 time
May 16 23:35:53   OpenSips[4378]: [ID 323951 local1.debug] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 13 chars, out: 13 chars
May 16 23:35:53   OpenSips[4378]: [ID 181770 local1.debug] DBG:db_postgres:db_postgres_submit_query: 100390e28 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.229.65.174:43669;transport=udp','2013-05-17 00:35:53',-1.00     ,'548361087116 at 100.229.65.174',2,0,0,'Sipdroid/3.0 beta/LG-P999','sip:208.54.44.253:44494',NULL,'udp:209.252.110.48:5060',NULL,'2013-05-16 23:35:53',NULL,'akanvoice.com'))
May 16 23:35:53   OpenSips[4378]: [ID 529718 local1.debug] DBG:core:db_new_result: allocate 48 bytes for result set at 100395bb8
May 16 23:35:53   OpenSips[4378]: [ID 911073 local1.debug] DBG:db_postgres:db_postgres_store_result: 100390e28 PQresultStatus(PGRES_COMMAND_OK) PQgetResult(100782820)
May 16 23:35:53   OpenSips[4378]: [ID 198008 local1.debug] DBG:db_postgres:free_query: PQclear(100782820) result set
May 16 23:35:53   OpenSips[4378]: [ID 670447 local1.debug] DBG:core:db_free_rows: freeing 0 rows
May 16 23:35:53   OpenSips[4378]: [ID 921065 local1.debug] DBG:core:db_free_result: freeing result set at 100395bb8
May 16 23:35:53   OpenSips[4378]: [ID 866151 local1.debug] DBG:registrar:build_contact: created Contact HF: Contact: <sip:nkeeling at 100.229.65.174:43669;transport=udp>;expires=3600;received="sip:208.54.44.253:44494"^M
May 16 23:35:53   OpenSips[4378]: [ID 497291 local1.debug] DBG:core:parse_headers: flags=ffffffffffffffff
May 16 23:35:53   OpenSips[4378]: [ID 153352 local1.debug] DBG:core:pv_printf: final buffer length 106
May 16 23:35:53   OpenSips[4378]: [ID 197553 local1.notice] NOTICE:callerid:548361087116 at 100.229.65.174|end|The subscriber has successfully registered with Akan Voice


More information about the Users mailing list