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;
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:nkeel...@akanvoice.com}
May 16 23:35:53 OpenSips[4378]: [ID 993225 local1.debug]
DBG:core:get_hdr_field: <To> [30]; uri=[sip:nkeel...@akanvoice.com]
May 16 23:35:53 OpenSips[4378]: [ID 159376 local1.debug]
DBG:core:get_hdr_field: to body [<sip:nkeel...@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:nkeel...@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:nkeel...@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@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@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@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@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:nkeel...@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@100.229.65.174|log|from sip:nkeel...@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@100.229.65.174|log|to sip:nkeel...@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@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@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="nkeel...@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 nkeel...@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@100.229.65.174:43669;transport=udp','2013-05-17
00:35:53',-1.00 ,'548361087116@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@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@100.229.65.174|end|The subscriber has successfully
registered with Akan Voice
_______________________________________________
Users mailing list
Users@lists.opensips.org
http://lists.opensips.org/cgi-bin/mailman/listinfo/users