[OpenSIPS-Users] Registrar not saving received from Path header
Nathaniel L Keeling III
keeling at akan-tech.com
Sun May 5 18:49:57 CEST 2013
Hello,
On P1, I am using the add_path_received() function which is adding the
Path header. Actually, it adds 2 Path headers since the request comes in
using TCP but is forwarded using UDP. On the REGISTER, I use the
save("location","p0") function but the path header is not saved in the
PATH column of the location table. I have attached a snippet from the
REGISTER's log and it looks like the insert into the table in placing
NULL even though the PATH headers were successfully parsed earlier.
Also, will the lookup() function use this column to locate the
subscriber for an INVITE request?
Thanks
Nathaniel
On 5/5/13 5:55 AM, Bogdan-Andrei Iancu wrote:
> The registrar server will store the PATH hdr in the PATH column of the
> "location" table.
>
> If P1 adds a PATH hdr to a REGISTER that is saved on REGISTRAR via
> save(location, p0) .
>
> Regards,
>
> Bogdan-Andrei Iancu
> OpenSIPS Founder and Developer
> http://www.opensips-solutions.com
>
>
> On 05/04/2013 08:11 PM, Nathaniel L Keeling III wrote:
>> I am currently using version 1.8.2 of opensips. I am using this code
>> on the registrar server, save("location","p0v"), when the user is
>> authenticated. The user is behind a firewall. The register request is
>> first sent to the sip proxy which forwards it to the registrar
>> server. The sip proxy adds the Path header with the source IP/Port of
>> the Register request. From the documentation it sounds like the
>> save() function should take the "received" parameter from the Path
>> header and store it in the "received" column of the location table.
>> When I look at the location table it contains the IP address and port
>> of the SIP proxy so when I try to locate the user, they are being
>> sent to the SIP proxy and the call fails. Is my understanding
>> correct? What is the best approach for this, UAC --> firewall --> P1
>> --> REG.
>>
>> Thanks
>>
>> Nathaniel
>>
>> On 5/4/13 4:26 AM, Bogdan-Andrei Iancu wrote:
>>> Hello Nathaniel,
>>>
>>> See
>>> http://www.opensips.org/html/docs/modules/1.9.x/registrar.html#id248705
>>> - this controls the PATH support in REGISTRAR module.
>>>
>>> Regards,
>>>
>>> Bogdan-Andrei Iancu
>>> OpenSIPS Founder and Developer
>>> http://www.opensips-solutions.com
>>>
>>>
>>> On 05/04/2013 01:31 AM, Nathaniel L Keeling III wrote:
>>>> Hello,
>>>>
>>>> I sent an earlier post concerning NATed registrations not being
>>>> able to locate from the lookup() function when the registration
>>>> request is sent from a opensips proxy server to an opensips
>>>> registration server and from my research it looks like I should be
>>>> using the Path header with the received parameter set. Doing this,
>>>> the Register request is sent to the registrar proxy server with a
>>>> Path header, the user is successfully authorized and saved in the
>>>> location table but when I look at the location table entry, the
>>>> received column either does not contain a value or it contains the
>>>> wrong value. Here is the Register request sent from the proxy to
>>>> the registrar server and the output from the location table.
>>>>
>>>> REGISTER sip:my-sip-domain.com;transport=tcp SIP/2.0.
>>>> Call-ID: 541d070a84f74ca6f61f68732d063d35 at 0:0:0:0:0:0:0:0.
>>>> CSeq: 2 REGISTER.
>>>> From: "Nathaniel L Keeling III"
>>>> <sip:nkeeling3 at mydomain2.com>;tag=cbe17bd3.
>>>> To: "Nathaniel L Keeling III" <sip:nkeeling3 at mydomain2.com>.
>>>> Max-Forwards: 68.
>>>> User-Agent: Jitsi2.0.4506.10553Mac OS X.
>>>> Expires: 600.
>>>> Contact: "Nathaniel L Keeling III"
>>>> <sip:nkeeling3 at 192.168.43.237:65457;transport=tcp;registering_acc=mydomain2_com>;expires=600.
>>>> Via: SIP/2.0/UDP
>>>> xxx.xxx.110.38:5060;branch=z9hG4bK-383637-fa379c63d9b82d3f671742fe537882a1;i=04.
>>>> Via: SIP/2.0/TCP
>>>> 192.168.43.237:65457;received=208.54.44.148;branch=z9hG4bK-383637-fa379c63d9b82d3f671742fe537882a1.
>>>> Authorization: Digest
>>>> username="nkeeling3",realm="mydomain2.com",nonce="5184345b0000003b08c40d29a091fb53e6cb83c3961c1dbb",uri="sip:my-sip-domain.com;transport=tcp",response="987edb51f504ff56c7ba840d594c4bb1".
>>>> Content-Length: 0.
>>>> Path:
>>>> <sip:xxx.xxx.110.38;r2=on;lr;received=sip:208.54.44.148:43932;transport=tcp>.
>>>> Path: <sip:xxx.xxx.110.38;transport=tcp;r2=on;lr>.
>>>>
>>>>
>>>> id | username | domain |
>>>> contact | received |
>>>> path | expires | q | callid | cseq
>>>> | last_modified | flags | cflags | user_agent |
>>>> socket | methods | sip_instance
>>>> ------+-----------+---------------+--------------------------------------------------------------------------------+-------------------------+------+---------------------+----+--------------------------------------------------+------+---------------------+-------+--------+-----------------------------+-------------------------+---------+--------------
>>>>
>>>> 1555 | nkeeling3 | mydomain2.com |
>>>> sip:nkeeling3 at 192.168.43.237:65420;transport=tcp;registering_acc=mydomain2_com
>>>> | sip:xxx.xxx.110.38:5060 | | 2013-05-03 17:08:03 | -1 |
>>>> 869321ee55e10970ff139673909ab626 at 0:0:0:0:0:0:0:0 | 10 | 2013-05-03
>>>> 16:58:03 | 0 | 1024 | Jitsi2.0.4506.10553Mac OS X |
>>>> udp:xxx.xxx.110.48:5060 | |
>>>> 1556 | nkeeling3 | mydomain2.com |
>>>> sip:nkeeling3 at 192.168.43.237:65457;transport=tcp;registering_acc=mydomain2_com
>>>> | sip:xxx.xxx.110.38:5060 | | 2013-05-03 17:13:42 | -1 |
>>>> 541d070a84f74ca6f61f68732d063d35 at 0:0:0:0:0:0:0:0 | 2 | 2013-05-03
>>>> 17:03:42 | 0 | 1024 | Jitsi2.0.4506.10553Mac OS X |
>>>> udp:xxx.xxx.110.48:5060 | |
>>>>
>>>>
>>>> Thanks
>>>>
>>>> Nathaniel
>>>>
>>>>
>>>>
>>>> _______________________________________________
>>>> 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 --------------
May 5 11:22:29 [1018] DBG:core:parse_msg: SIP Request:
May 5 11:22:29 [1018] DBG:core:parse_msg: method: <REGISTER>
May 5 11:22:29 [1018] DBG:core:parse_msg: uri: <sip:kwesi-chicago-registrar1.akan.us.com;transport=tcp>
May 5 11:22:29 [1018] DBG:core:parse_msg: version: <SIP/2.0>
May 5 11:22:29 [1018] DBG:core:parse_headers: flags=2
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 6, name=<Call-ID>, body=<6ebef445713b17b098aa4948a7dcf1bf at 0:0:0:0:0:0:0:0>
May 5 11:22:29 [1018] DBG:core:get_hdr_field: cseq <CSeq>: <38> <REGISTER>
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 5, name=<CSeq>, body=<38 REGISTER>
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 4, name=<From>, body=<"Nathaniel L Keeling III" <sip:nkeeling3 at akanvoice.com>;tag=9932eb3d>
May 5 11:22:29 [1018] DBG:core:parse_to: end of header reached, state=10
May 5 11:22:29 [1018] DBG:core:parse_to: display={"Nathaniel L Keeling III"}, ruri={sip:nkeeling3 at akanvoice.com}
May 5 11:22:29 [1018] DBG:core:get_hdr_field: <To> [57]; uri=[sip:nkeeling3 at akanvoice.com]
May 5 11:22:29 [1018] DBG:core:get_hdr_field: to body ["Nathaniel L Keeling III" <sip:nkeeling3 at akanvoice.com>
]
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 3, name=<To>, body=<"Nathaniel L Keeling III" <sip:nkeeling3 at akanvoice.com>>
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 8, name=<Max-Forwards>, body=<68>
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 27, name=<User-Agent>, body=<Jitsi2.0.4506.10553Mac OS X>
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 15, name=<Expires>, body=<600>
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 7, name=<Contact>, body=<"Nathaniel L Keeling III" <sip:nkeeling3 at 192.168.1.187:53943;transport=tcp;registering_acc=akanvoice_com>;expires=600>
May 5 11:22:29 [1018] DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK-383637-6a0fc239ad5799b45d9926004e777391>; state=6
May 5 11:22:29 [1018] DBG:core:parse_via_param: found param type 236, <i> = <6>; state=16
May 5 11:22:29 [1018] DBG:core:parse_via: end of header reached, state=5
May 5 11:22:29 [1018] DBG:core:parse_headers: via found, flags=2
May 5 11:22:29 [1018] DBG:core:parse_headers: this is the first via
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 1, name=<Via>, body=<SIP/2.0/UDP 209.252.110.38:5060;branch=z9hG4bK-383637-6a0fc239ad5799b45d9926004e777391;i=6>
May 5 11:22:29 [1018] DBG:core:parse_msg: first via: <SIP/2.0/UDP> <209.252.110.38:5060(5060)>May 5 11:22:29 [1018] DBG:core:parse_msg: ;<branch=z9hG4bK-383637-6a0fc239ad5799b45d9926004e777391;i=6>May 5 11:22:29 [1018] DBG:core:parse_msg:
May 5 11:22:29 [1018] DBG:core:parse_msg: exiting
May 5 11:22:29 [1018] DBG:core:receive_msg: After parse_msg...
May 5 11:22:29 [1018] DBG:core:receive_msg: preparing to run routing scripts...
May 5 11:22:29 [1018] DBG:core:parse_uri: parsed uri:
type=1 user=<>(0)
passwd=<>(0)
host=<kwesi-chicago-registrar1.akan.us.com>(36)
port=<>(0): 0
params=<transport=tcp>(13)
headers=<>(0)
May 5 11:22:29 [1018] DBG:core:parse_uri: uri params:
transport=<transport=tcp>, val=<tcp>, proto=2
May 5 11:22:29 [1018] DBG:core:parse_uri: user-param=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: method=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: ttl=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: maddr=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: lr=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: r2=<>, val=<>
May 5 11:22:29 [1018] DBG:core:pv_printf: final buffer length 153
INFO:callerid:6ebef445713b17b098aa4948a7dcf1bf at 0:0:0:0:0:0:0:0|start|recieved tcp request REGISTER sip:kwesi-chicago-registrar1.akan.us.com;transport=tcpMay 5 11:22:29 [1018] DBG:core:pv_printf: final buffer length 104
INFO:callerid:6ebef445713b17b098aa4948a7dcf1bf at 0:0:0:0:0:0:0:0|log|source IP address 209.252.110.38:5060May 5 11:22:29 [1018] DBG:core:parse_uri: parsed uri:
type=1 user=<>(0)
passwd=<>(0)
host=<kwesi-chicago-registrar1.akan.us.com>(36)
port=<>(0): 0
params=<transport=tcp>(13)
headers=<>(0)
May 5 11:22:29 [1018] DBG:core:parse_uri: uri params:
transport=<transport=tcp>, val=<tcp>, proto=2
May 5 11:22:29 [1018] DBG:core:parse_uri: user-param=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: method=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: ttl=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: maddr=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: lr=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: r2=<>, val=<>
May 5 11:22:29 [1018] DBG:core:pv_printf: final buffer length 133
INFO:callerid:6ebef445713b17b098aa4948a7dcf1bf at 0:0:0:0:0:0:0:0|log|request uri sip:kwesi-chicago-registrar1.akan.us.com;transport=tcpMay 5 11:22:29 [1018] DBG:core:parse_to_param: tag=9932eb3d
May 5 11:22:29 [1018] DBG:core:parse_to: end of header reached, state=29
May 5 11:22:29 [1018] DBG:core:parse_to: display={"Nathaniel L Keeling III"}, ruri={sip:nkeeling3 at akanvoice.com}
May 5 11:22:29 [1018] DBG:core:pv_printf: final buffer length 99
INFO:callerid:6ebef445713b17b098aa4948a7dcf1bf at 0:0:0:0:0:0:0:0|log|from sip:nkeeling3 at akanvoice.comMay 5 11:22:29 [1018] DBG:core:pv_printf: final buffer length 97
INFO:callerid:6ebef445713b17b098aa4948a7dcf1bf at 0:0:0:0:0:0:0:0|log|to sip:nkeeling3 at akanvoice.comMay 5 11:22:29 [1018] DBG:core:pv_printf: final buffer length 103
INFO:callerid:6ebef445713b17b098aa4948a7dcf1bf at 0:0:0:0:0:0:0:0|log|Request sent from internal SIP proxyMay 5 11:22:29 [1018] DBG:core:parse_headers: flags=ffffffffffffffff
May 5 11:22:29 [1018] DBG:core:parse_via_param: found param type 235, <rport> = <53962>; state=6
May 5 11:22:29 [1018] DBG:core:parse_via_param: found param type 234, <received> = <75.182.52.170>; state=6
May 5 11:22:29 [1018] DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK-383637-6a0fc239ad5799b45d9926004e777391>; state=16
May 5 11:22:29 [1018] DBG:core:parse_via: end of header reached, state=5
May 5 11:22:29 [1018] DBG:core:parse_headers: via found, flags=ffffffffffffffff
May 5 11:22:29 [1018] DBG:core:parse_headers: parse_headers: this is the second via
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 1, name=<Via>, body=<SIP/2.0/TCP 192.168.1.187:53943;rport=53962;received=75.182.52.170;branch=z9hG4bK-383637-6a0fc239ad5799b45d9926004e777391>
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 14, name=<Authorization>, body=<Digest username="nkeeling3",realm="akanvoice.com",nonce="5186876300000000327774b54fb0f523909ed36349d21fe8",uri="sip:kwesi-chicago-registrar1.akan.us.com;transport=tcp",response="c250de85e284b0c1d9d1091bb85aa0b5">
May 5 11:22:29 [1018] DBG:core:get_hdr_field: content_length=0
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 13, name=<Content-Length>, body=<0>
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 11, name=<Path>, body=<<sip:209.252.110.38;r2=on;lr;received=sip:75.182.52.170:53962;transport=tcp>>
May 5 11:22:29 [1018] DBG:core:parse_headers: header field type 11, name=<Path>, body=<<sip:209.252.110.38;transport=tcp;r2=on;lr>>
May 5 11:22:29 [1018] DBG:core:get_hdr_field: found end of header
May 5 11:22:29 [1018] DBG:core:parse_params: Parsing params for:[expires=600]
May 5 11:22:29 [1018] DBG:sipmsgops:sip_validate_hdrs: duplicate header 'Content-Length'
May 5 11:22:29 [1018] DBG:maxfwd:w_process_maxfwd_header: value 67 decreased to 30
May 5 11:22:29 [1018] DBG:core:check_ip_address: params 209.252.110.38, 209.252.110.38, 0
May 5 11:22:29 [1018] DBG:core:parse_headers: flags=ffffffffffffffff
May 5 11:22:29 [1018] DBG:core:parse_uri: parsed uri:
type=1 user=<nkeeling3>(9)
passwd=<>(0)
host=<192.168.1.187>(13)
port=<53943>(5): 53943
params=<transport=tcp;registering_acc=akanvoice_com>(43)
headers=<>(0)
May 5 11:22:29 [1018] DBG:core:parse_uri: uri params:
transport=<transport=tcp>, val=<tcp>, proto=2
May 5 11:22:29 [1018] DBG:core:parse_uri: user-param=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: method=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: ttl=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: maddr=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: lr=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: r2=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: uname=[10033bba5]-><registering_acc> uval=[10033bbb5]-><akanvoice_com>
May 5 11:22:29 [1018] DBG:uri:has_totag: no totag
May 5 11:22:29 [1018] DBG:core:parse_headers: flags=78
May 5 11:22:29 [1018] DBG:tm:t_lookup_request: start searching: hash=55313, isACK=0
May 5 11:22:29 [1018] DBG:tm:matching_3261: RFC3261 transaction matching failed
May 5 11:22:29 [1018] DBG:tm:t_lookup_request: no transaction found
May 5 11:22:29 [1018] DBG:core:parse_headers: flags=200
May 5 11:22:29 [1018] DBG:rr:find_first_route: No Route headers found
May 5 11:22:29 [1018] DBG:rr:loose_route: There is no Route HF
May 5 11:22:29 [1018] DBG:core:parse_uri: parsed uri:
type=1 user=<nkeeling3>(9)
passwd=<>(0)
host=<akanvoice.com>(13)
port=<>(0): 0
params=<>(0)
headers=<>(0)
May 5 11:22:29 [1018] DBG:core:parse_uri: uri params:
transport=<>, val=<>, proto=0
May 5 11:22:29 [1018] DBG:core:parse_uri: user-param=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: method=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: ttl=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: maddr=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: lr=<>, val=<>
May 5 11:22:29 [1018] DBG:core:parse_uri: r2=<>, val=<>
May 5 11:22:29 [1018] DBG:auth:check_nonce: comparing [5186876300000000327774b54fb0f523909ed36349d21fe8] and [5186876300000000327774b54fb0f523909ed36349d21fe8]
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 9 chars, out: 9 chars
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 13 chars, out: 13 chars
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_submit_query: 100391af0 PQsendQuery(select password from subscriber where username='nkeeling3' AND domain='akanvoice.com')
May 5 11:22:29 [1018] DBG:core:db_new_result: allocate 48 bytes for result set at 100394a68
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_store_result: 100391af0 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(100782470)
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_get_columns: 1 columns returned from the query
May 5 11:22:29 [1018] DBG:core:db_allocate_columns: allocate 28 bytes for result columns at 100394900
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394908)[0]=[password]
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_convert_rows: allocate for 1 columns 8 bytes in row buffer at 100389320
May 5 11:22:29 [1018] DBG:core:db_allocate_rows: allocate 48 bytes for result rows and values at 100394938
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_convert_rows: PQgetvalue(100391af0,0,0)=[03nlk59]
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_convert_rows: allocated 7 bytes for row_buf[0] at 100389300
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_convert_rows: [0][0] Column[password]=[03nlk59]
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_str2val: converting STRING [03nlk59]
May 5 11:22:29 [1018] DBG:db_postgres:db_postgres_convert_rows: freeing row buffer at 100389320
May 5 11:22:30 [1018] DBG:db_postgres:free_query: PQclear(100782470) result set
May 5 11:22:30 [1018] DBG:auth_db:get_ha1: HA1 string calculated: 3633cc887a3f542872f6465514918c0c
May 5 11:22:30 [1018] DBG:auth:check_response: our result = 'c250de85e284b0c1d9d1091bb85aa0b5'
May 5 11:22:30 [1018] DBG:auth:check_response: authorization is OK
May 5 11:22:30 [1018] DBG:auth:post_auth: nonce index= 0
May 5 11:22:30 [1018] DBG:core:db_free_columns: freeing result columns at 100394900
May 5 11:22:30 [1018] DBG:core:db_free_rows: freeing 1 rows
May 5 11:22:30 [1018] DBG:core:db_free_row: freeing row values at 100394948
May 5 11:22:30 [1018] DBG:core:db_free_row: free VAL_STRING[0] '03nlk59' at 100389300
May 5 11:22:30 [1018] DBG:core:db_free_rows: freeing rows at 100394938
May 5 11:22:30 [1018] DBG:core:db_free_result: freeing result set at 100394a68
May 5 11:22:30 [1018] DBG:uri:check_username: Digest username and URI username match
May 5 11:22:30 [1018] DBG:core:pv_printf: final buffer length 98
SAVING THE SUBSCRIBER INTO THE LOCATION TABLE ....................................................May 5 11:22:30 [1018] DBG:core:parse_headers: flags=ffffffffffffffff
May 5 11:22:30 [1018] DBG:core:parse_uri: parsed uri:
type=1 user=<nkeeling3>(9)
passwd=<>(0)
host=<akanvoice.com>(13)
port=<>(0): 0
params=<>(0)
headers=<>(0)
May 5 11:22:30 [1018] DBG:core:parse_uri: uri params:
transport=<>, val=<>, proto=0
May 5 11:22:30 [1018] DBG:core:parse_uri: user-param=<>, val=<>
May 5 11:22:30 [1018] DBG:core:parse_uri: method=<>, val=<>
May 5 11:22:30 [1018] DBG:core:parse_uri: ttl=<>, val=<>
May 5 11:22:30 [1018] DBG:core:parse_uri: maddr=<>, val=<>
May 5 11:22:30 [1018] DBG:core:parse_uri: lr=<>, val=<>
May 5 11:22:30 [1018] DBG:core:parse_uri: r2=<>, val=<>
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 9 chars, out: 9 chars
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 13 chars, out: 13 chars
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_submit_query: 10038f938 PQsendQuery(select contact,expires,q,callid,cseq,flags,cflags,user_agent,received,path,socket,methods,last_modified,sip_instance from location where username='nkeeling3' AND domain='akanvoice.com' order by q)
May 5 11:22:30 [1018] DBG:core:db_new_result: allocate 48 bytes for result set at 100394a68
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_store_result: 10038f938 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(1007824e0)
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: 14 columns returned from the query
May 5 11:22:30 [1018] DBG:core:db_allocate_columns: allocate 392 bytes for result columns at 100394ac0
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394b30)[0]=[contact]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394b40)[1]=[expires]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_DATETIME result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394b50)[2]=[q]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_DOUBLE result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394b60)[3]=[callid]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394b70)[4]=[cseq]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_INT result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394b80)[5]=[flags]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_INT result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394b90)[6]=[cflags]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_INT result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394ba0)[7]=[user_agent]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394bb0)[8]=[received]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394bc0)[9]=[path]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394bd0)[10]=[socket]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394be0)[11]=[methods]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_INT result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394bf0)[12]=[last_modified]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_DATETIME result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: RES_NAMES(100394c00)[13]=[sip_instance]
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_get_columns: use DB_STRING result type
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_convert_rows: no rows returned from the query
May 5 11:22:30 [1018] DBG:db_postgres:free_query: PQclear(1007824e0) result set
May 5 11:22:30 [1018] DBG:usrloc:db_load_urecord: aor nkeeling3 at akanvoice.com not found in table location
May 5 11:22:30 [1018] DBG:core:db_free_columns: freeing result columns at 100394ac0
May 5 11:22:30 [1018] DBG:core:db_free_rows: freeing 0 rows
May 5 11:22:30 [1018] DBG:core:db_free_result: freeing result set at 100394a68
May 5 11:22:30 [1018] DBG:core:parse_headers: flags=8000000
May 5 11:22:30 [1018] DBG:core:parse_headers: flags=ffffffffffffffff
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 9 chars, out: 9 chars
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 77 chars, out: 77 chars
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 48 chars, out: 48 chars
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 27 chars, out: 27 chars
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 23 chars, out: 23 chars
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 23 chars, out: 23 chars
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_val2str: PQescapeStringConn: in: 13 chars, out: 13 chars
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_submit_query: 10038f938 PQsendQuery(insert into location (username,contact,expires,q,callid,cseq,flags,cflags,user_agent,received,path,socket,methods,last_modified,sip_instance,domain ) values ('nkeeling3','sip:nkeeling3 at 192.168.1.187:53943;transport=tcp;registering_acc=akanvoice_com','2013-05-05 11:32:30',-1.00 ,'6ebef445713b17b098aa4948a7dcf1bf at 0:0:0:0:0:0:0:0',38,0,1024,'Jitsi2.0.4506.10553Mac OS X','sip:209.252.110.38:5060',NULL,'udp:209.252.110.48:5060',NULL,'2013-05-05 11:22:30',NULL,'akanvoice.com'))
May 5 11:22:30 [1018] DBG:core:db_new_result: allocate 48 bytes for result set at 100394a68
May 5 11:22:30 [1018] DBG:db_postgres:db_postgres_store_result: 10038f938 PQresultStatus(PGRES_COMMAND_OK) PQgetResult(1007825f0)
May 5 11:22:30 [1018] DBG:db_postgres:free_query: PQclear(1007825f0) result set
May 5 11:22:30 [1018] DBG:core:db_free_rows: freeing 0 rows
May 5 11:22:30 [1018] DBG:core:db_free_result: freeing result set at 100394a68
May 5 11:22:30 [1018] DBG:registrar:build_contact: created Contact HF: Contact: <sip:nkeeling3 at 192.168.1.187:53943;transport=tcp;registering_acc=akanvoice_com>;expires=600;received="sip:209.252.110.38:5060"
May 5 11:22:30 [1018] DBG:core:parse_headers: flags=ffffffffffffffff
May 5 11:22:30 [1018] DBG:sl:run_sl_callbacks: callback id 0 entered
May 5 11:22:30 [1018] DBG:core:pv_printf: final buffer length 127
NOTICE:callerid:6ebef445713b17b098aa4948a7dcf1bf at 0:0:0:0:0:0:0:0|end|The subscriber has successfully registered with Akan VoiceMay 5 11:22:30 [1018] DBG:core:destroy_avp_list: destroying list ffffffff392ff080
May 5 11:22:30 [1018] DBG:core:receive_msg: cleaning up
More information about the Users
mailing list