23 Oct
2004
23 Oct
'04
6:39 a.m.
Hi All.
I'm using ser-0.8.99-dev12 and really having problems getting usrloc to write
to MySQL.
Here is the usrloc modparams from my ser.cfg
modparam("usrloc", "db_mode", 1)
modparam("usrloc", "timer_interval", 10)
modparam("usrloc", "use_domain", 0)
modparam("usrloc", "db_url",
"mysql://ser:heslo@localhost/ser")
Below is the debug output from ser from the point the usrloc module is
initialized. It includes a UA registering with my ser proxy. I do not see any
indication here that usrloc has indeed invoked MySQL. Should I?
I'm really struggling to see what depedency is causing usrloc to not write to
the location table in MySQL. Have I missed something obvious?
Regards,
Paul
1(0) DEBUG: init_mod_child (-1): usrloc
1(0) get_connection(): Connection not found in the pool
2(0) INFO: fifo process starting: 19508
2(0) DEBUG: init_mod_child (-2): tm
2(0) DEBUG: callid: '7fbefbeb-0(a)172.16.1.153'
2(0) DEBUG: init_mod_child (-2): usrloc
2(0) get_connection(): Connection not found in the pool
0(19503) DEBUG: init_mod_child (1): tm
0(19503) DEBUG: callid: '7fbefbeb-19503(a)172.16.1.153'
0(19503) DEBUG: init_mod_child (1): usrloc
0(19503) get_connection(): Connection not found in the pool
1(19507) DEBUG: init_mod_child (-1): auth_db
1(19507) get_connection(): Connection found in the pool
2(19508) DEBUG: init_mod_child (-2): auth_db
2(19508) get_connection(): Connection found in the pool
2(19508) SER: open_uac_fifo: fifo server up at /tmp/ser_fifo...
2(19508) DEBUG: register_fifo_cmd: new command (print) registered
2(19508) DEBUG: register_fifo_cmd: new command (uptime) registered
2(19508) DEBUG: register_fifo_cmd: new command (version) registered
2(19508) DEBUG: register_fifo_cmd: new command (pwd) registered
2(19508) DEBUG: register_fifo_cmd: new command (arg) registered
2(19508) DEBUG: register_fifo_cmd: new command (which) registered
2(19508) DEBUG: register_fifo_cmd: new command (ps) registered
2(19508) DEBUG: register_fifo_cmd: new command (kill) registered
2(19508) find_mod_export: found <db_use_table> in module mysql
[/usr/local/lib/ser/modules/mysql.so]
2(19508) find_mod_export: found <db_init> in module mysql
[/usr/local/lib/ser/modules/mysql.so]
2(19508) find_mod_export: found <db_close> in module mysql
[/usr/local/lib/ser/modules/mysql.so]
2(19508) find_mod_export: found <db_query> in module mysql
[/usr/local/lib/ser/modules/mysql.so]
2(19508) find_mod_export: found <db_raw_query> in module mysql
[/usr/local/lib/ser/modules/mysql.so]
2(19508) find_mod_export: found <db_free_result> in module mysql
[/usr/local/lib/ser/modules/mysql.so]
2(19508) find_mod_export: found <db_insert> in module mysql
[/usr/local/lib/ser/modules/mysql.so]
2(19508) find_mod_export: found <db_delete> in module mysql
[/usr/local/lib/ser/modules/mysql.so]
2(19508) find_mod_export: found <db_update> in module mysql
[/usr/local/lib/ser/modules/mysql.so]
2(19508) get_connection(): Connection found in the pool
2(19508) DEBUG: register_fifo_cmd: new command (DB) registered
0(19503) DEBUG: init_mod_child (1): auth_db
0(19503) get_connection(): Connection found in the pool
0(19493) SIP Request:
0(19493) method: <REGISTER>
0(19493) uri: <sip:172.16.1.153>
0(19493) version: <SIP/2.0>
0(19493) parse_headers: flags=1
0(19493) Found param type 232, <branch> = <z9hG4bKff4cd4ff4ac4a763>;
state=16
0(19493) end of header reached, state=5
0(19493) parse_headers: Via found, flags=1
0(19493) parse_headers: this is the first via
0(19493) After parse_msg...
0(19493) preparing to run routing scripts...
0(19493) parse_headers: flags=128
0(19493) end of header reached, state=9
0(19493) DEBUG: get_hdr_field: <To> [36];
uri=[sip:1010@172.16.1.153;user=phone]
0(19493) DEBUG: to body [<sip:1010@172.16.1.153;user=phone>]
0(19493) get_hdr_field: cseq <CSeq>: <100> <REGISTER>
0(19493) DEBUG:maxfwd:is_maxfwd_present: value = 70
0(19493) DBG:maxfwd:process_maxfwd_header: value 70 decreased to 16
0(19493) parse_headers: flags=256
0(19493) DEBUG: get_hdr_body : content_length=0
0(19493) found end of header
0(19493) find_first_route(): No Route headers found
0(19493) loose_route(): There is no Route HF
0(19493) grep_sock_info - checking if host==us: 12==12 && [172.16.1.153] ==
[172.16.1.153]
0(19493) grep_sock_info - checking if port 5060 matches port 5060
0(19493) grep_sock_info - checking if host==us: 12==12 && [172.16.1.153] ==
[172.16.1.153]
0(19493) grep_sock_info - checking if port 5060 matches port 5060
0(19493) parse_headers: flags=4096
0(19493) pre_auth(): Credentials with given realm not found
0(19493) build_auth_hf(): 'WWW-Authenticate: Digest realm="172.16.1.153",
nonce="4179de37684a1d7693f0c437a809fe1946afd90d"
'
0(19493) parse_headers: flags=-1
0(19493) check_via_address(172.16.1.34, 172.16.1.34, 0)
0(19493) DEBUG:destroy_avp_list: destroying list (nil)
0(19493) receive_msg: cleaning up
0(19493) SIP Request:
0(19493) method: <REGISTER>
0(19493) uri: <sip:172.16.1.153>
0(19493) version: <SIP/2.0>
0(19493) parse_headers: flags=1
0(19493) Found param type 232, <branch> = <z9hG4bK8e1345c1d3d54874>;
state=16
0(19493) end of header reached, state=5
0(19493) parse_headers: Via found, flags=1
0(19493) parse_headers: this is the first via
0(19493) After parse_msg...
0(19493) preparing to run routing scripts...
0(19493) parse_headers: flags=128
0(19493) end of header reached, state=9
0(19493) DEBUG: get_hdr_field: <To> [36];
uri=[sip:1010@172.16.1.153;user=phone]
0(19493) DEBUG: to body [<sip:1010@172.16.1.153;user=phone>]
0(19493) get_hdr_field: cseq <CSeq>: <101> <REGISTER>
0(19493) DEBUG:maxfwd:is_maxfwd_present: value = 70
0(19493) DBG:maxfwd:process_maxfwd_header: value 70 decreased to 16
0(19493) parse_headers: flags=256
0(19493) DEBUG: get_hdr_body : content_length=0
0(19493) found end of header
0(19493) find_first_route(): No Route headers found
0(19493) loose_route(): There is no Route HF
0(19493) grep_sock_info - checking if host==us: 12==12 && [172.16.1.153] ==
[172.16.1.153]
0(19493) grep_sock_info - checking if port 5060 matches port 5060
0(19493) grep_sock_info - checking if host==us: 12==12 && [172.16.1.153] ==
[172.16.1.153]
0(19493) grep_sock_info - checking if port 5060 matches port 5060
0(19493) check_nonce(): comparing [4179de37684a1d7693f0c437a809fe1946afd90d]
and [4179de37684a1d7693f0c437a809fe1946afd90d]
0(19493) HA1 string calculated: 9c6b1b6480acbc2f87248ca008fb0dc1
0(19493) check_response(): Our result = 'adf927277baaa915be63eaef73e86cb6'
0(19493) check_response(): Authorization is OK
0(19493) save_rpid(): rpid value is ''
0(19493) parse_headers: flags=-1
0(19493) parse_headers: flags=33554432
0(19493) parse_headers: flags=-1
0(19493) check_via_address(172.16.1.34, 172.16.1.34, 0)
0(19493) DEBUG:destroy_avp_list: destroying list (nil)
0(19493) receive_msg: cleaning up
0(19493) SIP Request:
0(19493) method: <REGISTER>
0(19493) uri: <sip:172.16.1.153>
0(19493) version: <SIP/2.0>
0(19493) parse_headers: flags=1
0(19493) Found param type 232, <branch> = <z9hG4bKab4d5f63f40008ae>;
state=16
0(19493) end of header reached, state=5
0(19493) parse_headers: Via found, flags=1
0(19493) parse_headers: this is the first via
0(19493) After parse_msg...
0(19493) preparing to run routing scripts...
0(19493) parse_headers: flags=128
0(19493) end of header reached, state=9
0(19493) DEBUG: get_hdr_field: <To> [36];
uri=[sip:1010@172.16.1.153;user=phone]
0(19493) DEBUG: to body [<sip:1010@172.16.1.153;user=phone>]
0(19493) get_hdr_field: cseq <CSeq>: <102> <REGISTER>
0(19493) DEBUG:maxfwd:is_maxfwd_present: value = 70
0(19493) DBG:maxfwd:process_maxfwd_header: value 70 decreased to 16
0(19493) parse_headers: flags=256
0(19493) DEBUG: get_hdr_body : content_length=0
0(19493) found end of header
0(19493) find_first_route(): No Route headers found
0(19493) loose_route(): There is no Route HF
0(19493) grep_sock_info - checking if host==us: 12==12 && [172.16.1.153] ==
[172.16.1.153]
0(19493) grep_sock_info - checking if port 5060 matches port 5060
0(19493) grep_sock_info - checking if host==us: 12==12 && [172.16.1.153] ==
[172.16.1.153]
0(19493) grep_sock_info - checking if port 5060 matches port 5060
0(19493) parse_headers: flags=4096
0(19493) pre_auth(): Credentials with given realm not found
0(19493) build_auth_hf(): 'WWW-Authenticate: Digest realm="172.16.1.153",
nonce="4179de38e9c06f02608126486e382024120644e9"'
0(19493) parse_headers: flags=-1
0(19493) check_via_address(172.16.1.34, 172.16.1.34, 0)
0(19493) DEBUG:destroy_avp_list: destroying list (nil)
0(19493) receive_msg: cleaning up
0(19493) SIP Request:
0(19493) method: <REGISTER>
0(19493) uri: <sip:172.16.1.153>
0(19493) version: <SIP/2.0>
0(19493) parse_headers: flags=1
0(19493) Found param type 232, <branch> = <z9hG4bKe5b2bd5112750414>;
state=16
0(19493) end of header reached, state=5
0(19493) parse_headers: Via found, flags=1
0(19493) parse_headers: this is the first via
0(19493) After parse_msg...
0(19493) preparing to run routing scripts...
0(19493) parse_headers: flags=128
0(19493) end of header reached, state=9
0(19493) DEBUG: get_hdr_field: <To> [36];
uri=[sip:1010@172.16.1.153;user=phone]
0(19493) DEBUG: to body [<sip:1010@172.16.1.153;user=phone>]
0(19493) get_hdr_field: cseq <CSeq>: <103> <REGISTER>
0(19493) DEBUG:maxfwd:is_maxfwd_present: value = 70
0(19493) DBG:maxfwd:process_maxfwd_header: value 70 decreased to 16
0(19493) parse_headers: flags=256
0(19493) DEBUG: get_hdr_body : content_length=0
0(19493) found end of header
0(19493) find_first_route(): No Route headers found
0(19493) loose_route(): There is no Route HF
0(19493) grep_sock_info - checking if host==us: 12==12 && [172.16.1.153] ==
[172.16.1.153]
0(19493) grep_sock_info - checking if port 5060 matches port 5060
0(19493) grep_sock_info - checking if host==us: 12==12 && [172.16.1.153] ==
[172.16.1.153]
0(19493) grep_sock_info - checking if port 5060 matches port 5060
0(19493) check_nonce(): comparing [4179de38e9c06f02608126486e382024120644e9]
and [4179de38e9c06f02608126486e382024120644e9]
0(19493) HA1 string calculated: 9c6b1b6480acbc2f87248ca008fb0dc1
0(19493) check_response(): Our result = '210118ea1c3c6ea71518bf310f18ca9a'
0(19493) check_response(): Authorization is OK
0(19493) save_rpid(): rpid value is ''
0(19493) parse_headers: flags=-1
0(19493) parse_headers: flags=33554432
0(19493) build_contact(): Created Contact HF: Contact:
<sip:1010@172.16.1.34;user=phone>;expires=3600
0(19493) parse_headers: flags=-1
0(19493) check_via_address(172.16.1.34, 172.16.1.34, 0)
0(19493) DEBUG:destroy_avp_list: destroying list (nil)
0(19493) receive_msg: cleaning up
_______________________________
Do you Yahoo!?
Declare Yourself - Register online to vote today!
http://vote.yahoo.com