[OpenSIPS-Users] Trouble getting mediaproxy to work (dialog module)

slophoo dan.hislop at gipscorp.com
Fri Mar 20 23:07:06 CET 2009


I should say first that I am rather new to this, and am positive the mistake
is mine, just don't know what it is.  The dialog module does load ok in
startup, i guess i will post my startup debug here to show that. I've looked
at the documentation for engage_media_proxy (saying "This needs to be called
only once for the first INVITE in a dialog.") and despite reading the
documentation for dialog module, I still am missing something.  Is a dialog
something i am supposed to create/setup before I can use mediaproxy --  Or
is it something i am automatically in when making a call? 

-----------SELECTED LINES from STARTUP LOG ------------ENTIRE LOG POSTED AT
END of POST----- 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/dialog.so 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: dialog matches module
dialog 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <dlg_flag> in
module dialog [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module dialog 
Mar 20 14:56:22 [4818] INFO:dialog:mod_init: Dialog module - initializing 
Mar 20 14:56:22 [4818] DBG:core:init_mod: registering stats for dialog 
Mar 20 14:56:22 [4818] DBG:core:init_mod: register MI for dialog 
Mar 20 14:56:22 [4818] DBG:core:find_cmd_export_t: found <load_dlg>(0) in
module dialog [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:find_param_export: found <dlg_flag> in
module dialog [/usr/local/lib/opensips/modules/] 


Then when a call is placed, I get the error: 

ERROR:mediaproxy:EngageMediaProxy: engage_media_proxy requires the dialog
module to be loaded and configured

-------------HERE is the error in context: ---------------------------- 
root at sfsip:/usr/local/lib/opensips/modules# 
root at sfsip:/usr/local/lib/opensips/modules# 
root at sfsip:/usr/local/lib/opensips/modules# Mar 20 15:00:39 [4198]
DBG:core:parse_msg: SIP Request: 
Mar 20 15:00:39 [4198] DBG:core:parse_msg:  method:  <INVITE> 
Mar 20 15:00:39 [4198] DBG:core:parse_msg:  uri:     <sip:4030 at 10.1.100.18> 
Mar 20 15:00:39 [4198] DBG:core:parse_msg:  version: <SIP/2.0> 
Mar 20 15:00:39 [4198] DBG:core:parse_headers: flags=2 
Mar 20 15:00:39 [4198] DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK-d87543-cb64ea47ca0cf24a-1--d87543->; state=6 
Mar 20 15:00:39 [4198] DBG:core:parse_via_param: found param type 235,
<rport> = <n/a>; state=17 
Mar 20 15:00:39 [4198] DBG:core:parse_via: end of header reached, state=5 
Mar 20 15:00:39 [4198] DBG:core:parse_headers: via found, flags=2 
Mar 20 15:00:39 [4198] DBG:core:parse_headers: this is the first via 
Mar 20 15:00:39 [4198] DBG:core:receive_msg: After parse_msg... 
Mar 20 15:00:39 [4198] DBG:core:receive_msg: preparing to run routing
scripts... 
Mar 20 15:00:39 [4198] DBG:core:parse_headers: flags=100 
Mar 20 15:00:39 [4198] DBG:maxfwd:is_maxfwd_present: value = 70 
Mar 20 15:00:39 [4198] DBG:core:parse_headers: flags=8 
Mar 20 15:00:39 [4198] DBG:core:parse_to: end of header reached, state=10 
Mar 20 15:00:39 [4198] DBG:core:parse_to: display={"4030"},
ruri={sip:4030 at 10.1.100.18} 
Mar 20 15:00:39 [4198] DBG:core:get_hdr_field: <To> [30];
uri=[sip:4030 at 10.1.100.18] 
Mar 20 15:00:39 [4198] DBG:core:get_hdr_field: to body
["4030"<sip:4030 at 10.1.100.18> 
] 
Mar 20 15:00:39 [4198] DBG:uri:has_totag: no totag 
Mar 20 15:00:39 [4198] DBG:core:parse_headers: flags=78 
Mar 20 15:00:39 [4198] DBG:core:get_hdr_field: cseq <CSeq>: <1> <INVITE> 
Mar 20 15:00:39 [4198] DBG:tm:t_lookup_request: start searching: hash=260,
isACK=0 
Mar 20 15:00:39 [4198] DBG:tm:matching_3261: RFC3261 transaction matching
failed 
Mar 20 15:00:39 [4198] DBG:tm:t_lookup_request: no transaction found 
Mar 20 15:00:39 [4198] DBG:core:parse_headers: flags=ffffffffffffffff 
Mar 20 15:00:39 [4198] DBG:core:get_hdr_field: content_length=421 
Mar 20 15:00:39 [4198] DBG:core:get_hdr_field: found end of header 
Mar 20 15:00:39 [4198] DBG:core:grep_sock_info: checking if host==us: 11==9
&&  [10.1.100.18] == [127.0.0.1] 
Mar 20 15:00:39 [4198] DBG:core:grep_sock_info: checking if port 5060
matches port 5060 
Mar 20 15:00:39 [4198] DBG:core:grep_sock_info: checking if host==us: 11==11
&&  [10.1.100.18] == [10.1.100.18] 
Mar 20 15:00:39 [4198] DBG:core:grep_sock_info: checking if port 5060
matches port 5060 
Mar 20 15:00:39 [4198] DBG:core:rewrite_uri: rewriting Request-URI with
'sip:4030 at 192.168.1.94:5060;transport=UDP' 
Mar 20 15:00:39 [4198] ERROR:mediaproxy:EngageMediaProxy: engage_media_proxy
requires the dialog module to be loaded and configured 
Mar 20 15:00:39 [4198] DBG:core:parse_headers: flags=ffffffffffffffff 
Mar 20 15:00:39 [4198] DBG:tm:t_newtran: transaction on entrance=(nil) 
-------------END of error in context ---------------------------- 





-------------------ENTIRE STARTUP DEBUG LOG STARTS
HERE-------------------------------- 
root at sfsip:/usr/local/lib/opensips/modules# 
root at sfsip:/usr/local/lib/opensips/modules# opensips start 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/db_mysql.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/sl.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/tm.so 
Mar 20 14:56:22 [4816] DBG:core:register_module: register_pv: tm 
Mar 20 14:56:22 [4816] DBG:core:pv_add_extra: extra items list is not
initialized 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/rr.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/maxfwd.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/usrloc.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/registrar.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/textops.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/mi_fifo.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/uri_db.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/uri.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/xlog.so 
Mar 20 14:56:22 [4816] DBG:core:register_module: register_pv: xlog 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/dialog.so 
Mar 20 14:56:22 [4816] DBG:core:register_module: register_pv: dialog 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/acc.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/auth.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/auth_db.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/domain.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/nathelper.so 
Mar 20 14:56:22 [4816] DBG:core:yyparse: loading module
/usr/local/lib/opensips/modules/mediaproxy.so 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: mi_fifo matches module
mi_fifo 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <fifo_name> in
module mi_fifo [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: rr matches module rr 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <enable_full_lr>
in module rr [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: rr matches module rr 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <append_fromtag>
in module rr [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: registrar matches
module registrar 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found
<method_filtering> in module registrar [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: registrar matches
module registrar 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <max_contacts> in
module registrar [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: uri_db matches module
uri_db 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <use_uri_table>
in module uri_db [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: uri_db matches module
uri_db 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <db_url> in
module uri_db [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: registrar matches
module registrar 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <received_avp> in
module registrar [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: dialog matches module
dialog 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <dlg_flag> in
module dialog [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: acc matches module acc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <early_media> in
module acc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: acc matches module acc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <report_ack> in
module acc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: acc matches module acc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <report_cancels>
in module acc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: acc matches module acc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found
<detect_direction> in module acc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: acc matches module acc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found
<failed_transaction_flag> in module acc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: acc matches module acc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <log_flag> in
module acc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: acc matches module acc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <log_missed_flag>
in module acc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: acc matches module acc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <db_flag> in
module acc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: acc matches module acc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <db_missed_flag>
in module acc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: usrloc matches module
usrloc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <db_mode> in
module usrloc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: usrloc matches module
usrloc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <db_mode> in
module usrloc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: usrloc matches module
usrloc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <db_url> in
module usrloc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: auth_db matches module
auth_db 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <calculate_ha1>
in module auth_db [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: auth_db matches module
auth_db 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <password_column>
in module auth_db [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: auth_db matches module
auth_db 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <db_url> in
module auth_db [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: auth_db matches module
auth_db 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found
<load_credentials> in module auth_db [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: domain matches module
domain 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <db_url> in
module domain [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: domain matches module
domain 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <db_mode> in
module domain [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex:
alias_db|auth_db|usrloc|uri_db matches module auth_db 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <use_domain> in
module auth_db [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex:
alias_db|auth_db|usrloc|uri_db matches module uri_db 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <use_domain> in
module uri_db [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex:
alias_db|auth_db|usrloc|uri_db matches module usrloc 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <use_domain> in
module usrloc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: nathelper matches
module nathelper 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found
<natping_interval> in module nathelper [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: nathelper matches
module nathelper 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found <received_avp> in
module nathelper [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: mediaproxy matches
module mediaproxy 
Mar 20 14:56:22 [4816] DBG:core:set_mod_param_regex: found
<mediaproxy_socket> in module mediaproxy [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found
<mf_process_maxfwd_header>(1) in module maxfwd
[/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <sl_send_reply>(2)
in module sl [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <has_totag>(0) in
module uri [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <loose_route>(0) in
module rr [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <is_method>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <is_method>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <t_check_trans>(0)
in module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <t_relay>(0) in
module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <sl_send_reply>(2)
in module sl [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <is_method>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <t_check_trans>(0)
in module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <t_relay>(0) in
module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <t_check_trans>(0)
in module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <is_method>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <append_hf>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found
<engage_media_proxy>(0) in module mediaproxy
[/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <record_route>(0)
in module rr [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <is_method>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <append_hf>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <is_method>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <append_hf>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <sl_send_reply>(2)
in module sl [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <is_method>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <append_hf>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <search>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found
<fix_nated_register>(0) in module nathelper
[/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <save>(1) in module
registrar [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <sl_reply_error>(0)
in module sl [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <sl_send_reply>(2)
in module sl [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <lookup>(1) in
module registrar [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <t_newtran>(0) in
module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <t_reply>(2) in
module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <sl_send_reply>(2)
in module sl [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <is_method>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found
<engage_media_proxy>(0) in module mediaproxy
[/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <append_hf>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <t_on_branch>(1) in
module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <t_on_reply>(1) in
module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <t_on_failure>(1)
in module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <t_relay>(0) in
module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <sl_reply_error>(0)
in module sl [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <xlog>(1) in module
xlog [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <append_hf>(1) in
module textops [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found <xlog>(1) in module
xlog [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:find_cmd_export_t: found
<t_was_cancelled>(0) in module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4816] DBG:core:socket2str: <udp:127.0.0.1:5060> 
Mar 20 14:56:22 [4816] DBG:core:socket2str: <udp:10.1.100.18:5060> 
Mar 20 14:56:22 [4816] DBG:core:socket2str: <tcp:127.0.0.1:5060> 
Mar 20 14:56:22 [4816] DBG:core:socket2str: <tcp:10.1.100.18:5060> 
Listening on 
             udp: 127.0.0.1 [127.0.0.1]:5060 
             udp: 10.1.100.18 [10.1.100.18]:5060 
             tcp: 127.0.0.1 [127.0.0.1]:5060 
             tcp: 10.1.100.18 [10.1.100.18]:5060 
Aliases: 
             tcp: sfsip:5060 
             tcp: sfsip.globalip.int:5060 
             tcp: localhost:5060 
             udp: sfsip:5060 
             udp: sfsip.globalip.int:5060 
             udp: localhost:5060 

Mar 20 14:56:22 [4816] DBG:core:fm_malloc_init: F_OPTIMIZE=16384,
/ROUNDTO=2048 
Mar 20 14:56:22 [4816] DBG:core:fm_malloc_init: F_HASH_SIZE=2067, fm_block
size=16560 
Mar 20 14:56:22 [4816] DBG:core:fm_malloc_init: params (0xb597b000,
33554432), start=0xb597b000 
Mar 20 14:56:22 [4816] DBG:core:shm_mem_init_mallocs: success 
Mar 20 14:56:22 [4816] INFO:core:init_tcp: using epoll_lt as the TCP io
watch method (auto detected) 
Mar 20 14:56:22 [4818] DBG:core:set_core_dump: core dump limits set to
4294967295 
Mar 20 14:56:22 [4818] NOTICE:core:main: version: opensips 1.4.4-notls
(i386/linux) 
Mar 20 14:56:22 [4818] INFO:core:main: using 32 Mb shared memory 
Mar 20 14:56:22 [4818] INFO:core:main: using 1 Mb private memory per process 
Mar 20 14:56:22 [4818] DBG:core:add_avp_galias: registering <serial_branch>
for avp id <16725044> 
Mar 20 14:56:22 [4818] DBG:core:init_stats_collector: statistics manager
successfully initialized 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module db_mysql 
Mar 20 14:56:22 [4818] DBG:db_mysql:mysql_mod_init: mysql: MySQL client
version is 5.0.67 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module sl 
Mar 20 14:56:22 [4818] INFO:sl:mod_init: Initializing StateLess engine 
Mar 20 14:56:22 [4818] DBG:core:MD5StringArray: MD5 calculated:
c97b4d1cb1f3d0da549e06a8d482ef63 
Mar 20 14:56:22 [4818] DBG:core:init_mod: registering stats for sl 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module tm 
Mar 20 14:56:22 [4818] INFO:tm:mod_init: TM - initializing... 
Mar 20 14:56:22 [4818] DBG:tm:init_callid: Call-ID initialization:
'7ffbe7db' 
Mar 20 14:56:22 [4818] DBG:tm:lock_initialize: lock initialization started 
Mar 20 14:56:22 [4818] DBG:core:MD5StringArray: MD5 calculated:
533cb9e91f4b999cf76861cbb9ed54ed 
root at sfsip:/usr/local/lib/opensips/modules# Mar 20 14:56:22 [4818]
DBG:core:MD5StringArray: MD5 calculated: 155c340f586c28d0300cf5a6ccf90d99 
Mar 20 14:56:22 [4818] DBG:core:init_mod: registering stats for tm 
Mar 20 14:56:22 [4818] DBG:core:init_mod: register MI for tm 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module rr 
Mar 20 14:56:22 [4818] DBG:rr:mod_init: rr - initializing 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module maxfwd 
Mar 20 14:56:22 [4818] INFO:maxfwd:mod_init: initializing... 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module usrloc 
Mar 20 14:56:22 [4818] DBG:usrloc:mod_init: initializing 
Mar 20 14:56:22 [4818] INFO:usrloc:ul_init_locks: locks array size 512 
Mar 20 14:56:22 [4818] DBG:core:find_mod_export: found <db_bind_api> in
module db_mysql [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:db_bind_mod: using db bind api for db_mysql 
Mar 20 14:56:22 [4818] DBG:core:init_mod: registering stats for usrloc 
Mar 20 14:56:22 [4818] DBG:core:init_mod: register MI for usrloc 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module registrar 
Mar 20 14:56:22 [4818] INFO:registrar:mod_init: initializing... 
Mar 20 14:56:22 [4818] DBG:core:find_cmd_export_t: found <load_sl>(0) in
module sl [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:find_cmd_export_t: found <ul_bind_usrloc>(1)
in module usrloc [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:init_mod: registering stats for registrar 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module textops 
Mar 20 14:56:22 [4818] INFO:textops:mod_init: initializing... 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module mi_fifo 
Mar 20 14:56:22 [4818] DBG:mi_fifo:mi_mod_init: testing fifo existance ... 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module uri_db 
Mar 20 14:56:22 [4818] DBG:uri_db:mod_init: uri_db - initializing 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module xlog 
Mar 20 14:56:22 [4818] INFO:xlog:mod_init: initializing... 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module dialog 
Mar 20 14:56:22 [4818] INFO:dialog:mod_init: Dialog module - initializing 
Mar 20 14:56:22 [4818] DBG:core:find_cmd_export_t: found <load_tm>(0) in
module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:find_cmd_export_t: found <load_rr>(0) in
module rr [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:init_mod: registering stats for dialog 
Mar 20 14:56:22 [4818] DBG:core:init_mod: register MI for dialog 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module acc 
Mar 20 14:56:22 [4818] INFO:acc:mod_init: initializing... 
Mar 20 14:56:22 [4818] DBG:core:find_cmd_export_t: found <load_tm>(0) in
module tm [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module auth 
Mar 20 14:56:22 [4818] INFO:auth:mod_init: initializing... 
Mar 20 14:56:22 [4818] DBG:core:find_cmd_export_t: found <load_sl>(0) in
module sl [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module auth_db 
Mar 20 14:56:22 [4818] INFO:auth_db:mod_init: initializing... 
Mar 20 14:56:22 [4818] DBG:core:find_mod_export: found <db_bind_api> in
module db_mysql [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:db_bind_mod: using db bind api for db_mysql 
Mar 20 14:56:22 [4818] DBG:core:find_cmd_export_t: found <bind_auth>(0) in
module auth [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:find_cmd_export_t: found <load_sl>(0) in
module sl [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module domain 
Mar 20 14:56:22 [4818] DBG:domain:mod_init: Initializing 
Mar 20 14:56:22 [4818] DBG:core:find_mod_export: found <db_bind_api> in
module db_mysql [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:db_bind_mod: using db bind api for db_mysql 
Mar 20 14:56:22 [4818] DBG:core:db_do_init: connection 0x8191328 not found
in pool 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_new_connection: opening
connection: mysql://xxxx:xxxx@127.0.0.1/openser 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_new_connection: connection type
is 127.0.0.1 via TCP/IP 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_new_connection: protocol
version is 10 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_new_connection: server version
is 5.0.67-0ubuntu6 
Mar 20 14:56:22 [4818] DBG:core:db_new_result: allocate 28 bytes for result
set at 0x81913e8 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns: 1 columns returned
from the query 
Mar 20 14:56:22 [4818] DBG:core:db_allocate_columns: allocate 4 bytes for
result names at 0x8191430 
Mar 20 14:56:22 [4818] DBG:core:db_allocate_columns: allocate 4 bytes for
result types at 0x8191440 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns: allocate 8 bytes
for RES_NAMES[0] at 0x8191450 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns:
RES_NAMES(0x8191450)[0]=[table_version] 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns: use DB_INT result
type 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_convert_rows: allocate 8 bytes
for rows at 0x8191460 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_convert_row: allocate 20 bytes
for row values at 0x8191470 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_str2val: converting INT [1] 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing 1 columns 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing RES_NAMES[0] at
0x8191450 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing result names at
0x8191430 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing result types at
0x8191440 
Mar 20 14:56:22 [4818] DBG:core:db_free_rows: freeing 1 rows 
Mar 20 14:56:22 [4818] DBG:core:db_free_row: freeing row values at 0x8191470 
Mar 20 14:56:22 [4818] DBG:core:db_free_rows: freeing rows at 0x8191460 
Mar 20 14:56:22 [4818] DBG:core:db_free_result: freeing result set at
0x81913e8 
Mar 20 14:56:22 [4818] DBG:core:db_new_result: allocate 28 bytes for result
set at 0x81913e8 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns: 1 columns returned
from the query 
Mar 20 14:56:22 [4818] DBG:core:db_allocate_columns: allocate 4 bytes for
result names at 0x8191460 
Mar 20 14:56:22 [4818] DBG:core:db_allocate_columns: allocate 4 bytes for
result types at 0x8191440 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns: allocate 8 bytes
for RES_NAMES[0] at 0x8191430 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns:
RES_NAMES(0x8191430)[0]=[domain] 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns: use DB_STRING
result type 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_convert_rows: no rows returned
from the query 
Mar 20 14:56:22 [4818] DBG:domain:reload_domain_table: Number of rows in
domain table: 0 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing 1 columns 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing RES_NAMES[0] at
0x8191430 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing result names at
0x8191460 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing result types at
0x8191440 
Mar 20 14:56:22 [4818] DBG:core:db_free_rows: freeing 0 rows 
Mar 20 14:56:22 [4818] DBG:core:db_free_result: freeing result set at
0x81913e8 
Mar 20 14:56:22 [4818] DBG:core:pool_remove: removing connection from the
pool 
Mar 20 14:56:22 [4818] DBG:core:init_mod: register MI for domain 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module nathelper 
Mar 20 14:56:22 [4818] DBG:core:init_mod: register MI for nathelper 
Mar 20 14:56:22 [4818] DBG:core:init_mod: initializing module mediaproxy 
Mar 20 14:56:22 [4818] DBG:core:find_cmd_export_t: found <load_dlg>(0) in
module dialog [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:find_param_export: found <dlg_flag> in
module dialog [/usr/local/lib/opensips/modules/] 
Mar 20 14:56:22 [4818] DBG:core:count_module_procs: modules require 1 extra
processes 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing
mf_process_maxfwd_header, line 210 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing sl_send_reply, line 211 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing has_totag, line 215 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing loose_route, line 218 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing is_method, line 219 
Mar 20 14:56:22 [4818] DBG:core:parse_methods: methods 0x8 
Mar 20 14:56:22 [4818] DBG:textops:fixup_method: using id for method [BYE/8] 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing is_method, line 231 
Mar 20 14:56:22 [4818] DBG:core:parse_methods: methods 0x4 
Mar 20 14:56:22 [4818] DBG:textops:fixup_method: using id for method [ACK/4] 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing t_check_trans, line 232 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing t_relay, line 234 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing sl_send_reply, line 241 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing is_method, line 249 
Mar 20 14:56:22 [4818] DBG:core:parse_methods: methods 0x2 
Mar 20 14:56:22 [4818] DBG:textops:fixup_method: using id for method
[CANCEL/2] 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing t_check_trans, line 251 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing t_relay, line 252 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing t_check_trans, line 256 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing is_method, line 275 
Mar 20 14:56:22 [4818] DBG:core:parse_methods: methods 0x180 
Mar 20 14:56:22 [4818] DBG:textops:fixup_method: using id for methods
[REGISTER,MESSAGE/384] 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing append_hf, line 276 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing engage_media_proxy, line
277 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing record_route, line 278 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing is_method, line 281 
Mar 20 14:56:22 [4818] DBG:core:parse_methods: methods 0x1 
Mar 20 14:56:22 [4818] DBG:textops:fixup_method: using id for method
[INVITE/1] 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing append_hf, line 288 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing is_method, line 308 
Mar 20 14:56:22 [4818] DBG:core:parse_methods: methods 0x2000 
Mar 20 14:56:22 [4818] DBG:textops:fixup_method: using id for method
[PUBLISH/8192] 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing append_hf, line 310 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing sl_send_reply, line 311 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing is_method, line 322 
Mar 20 14:56:22 [4818] DBG:core:parse_methods: methods 0x80 
Mar 20 14:56:22 [4818] DBG:textops:fixup_method: using id for method
[REGISTER/128] 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing append_hf, line 336 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing search, line 337 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing fix_nated_register, line
339 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing save, line 342 
Mar 20 14:56:22 [4818] DBG:core:db_do_init: connection 0x8191580 not found
in pool 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_new_connection: opening
connection: mysql://xxxx:xxxx@127.0.0.1/openser 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_new_connection: connection type
is 127.0.0.1 via TCP/IP 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_new_connection: protocol
version is 10 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_new_connection: server version
is 5.0.67-0ubuntu6 
Mar 20 14:56:22 [4818] DBG:core:db_new_result: allocate 28 bytes for result
set at 0x818e388 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns: 1 columns returned
from the query 
Mar 20 14:56:22 [4818] DBG:core:db_allocate_columns: allocate 4 bytes for
result names at 0x81915e8 
Mar 20 14:56:22 [4818] DBG:core:db_allocate_columns: allocate 4 bytes for
result types at 0x81915f8 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns: allocate 8 bytes
for RES_NAMES[0] at 0x8191608 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns:
RES_NAMES(0x8191608)[0]=[table_version] 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns: use DB_INT result
type 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_convert_rows: allocate 8 bytes
for rows at 0x8191618 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_convert_row: allocate 20 bytes
for row values at 0x8191628 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_str2val: converting INT [1004] 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing 1 columns 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing RES_NAMES[0] at
0x8191608 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing result names at
0x81915e8 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing result types at
0x81915f8 
Mar 20 14:56:22 [4818] DBG:core:db_free_rows: freeing 1 rows 
Mar 20 14:56:22 [4818] DBG:core:db_free_row: freeing row values at 0x8191628 
Mar 20 14:56:22 [4818] DBG:core:db_free_rows: freeing rows at 0x8191618 
Mar 20 14:56:22 [4818] DBG:core:db_free_result: freeing result set at
0x818e388 
Mar 20 14:56:22 [4818] DBG:core:db_new_result: allocate 28 bytes for result
set at 0x818e388 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns: 1 columns returned
from the query 
Mar 20 14:56:22 [4818] DBG:core:db_allocate_columns: allocate 4 bytes for
result names at 0x8191618 
Mar 20 14:56:22 [4818] DBG:core:db_allocate_columns: allocate 4 bytes for
result types at 0x81915f8 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns: allocate 8 bytes
for RES_NAMES[0] at 0x81915e8 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns:
RES_NAMES(0x81915e8)[0]=[username] 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_get_columns: use DB_STRING
result type 
Mar 20 14:56:22 [4818] DBG:db_mysql:db_mysql_convert_rows: no rows returned
from the query 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing 1 columns 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing RES_NAMES[0] at
0x81915e8 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing result names at
0x8191618 
Mar 20 14:56:22 [4818] DBG:core:db_free_columns: freeing result types at
0x81915f8 
Mar 20 14:56:22 [4818] DBG:core:db_free_rows: freeing 0 rows 
Mar 20 14:56:22 [4818] DBG:core:db_free_result: freeing result set at
0x818e388 
Mar 20 14:56:22 [4818] DBG:core:pool_remove: removing connection from the
pool 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing sl_reply_error, line 343 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing sl_send_reply, line 350 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing lookup, line 357 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing t_newtran, line 361 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing t_reply, line 362 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing sl_send_reply, line 365 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing is_method, line 378 
Mar 20 14:56:22 [4818] DBG:core:parse_methods: methods 0x1 
Mar 20 14:56:22 [4818] DBG:textops:fixup_method: using id for method
[INVITE/1] 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing engage_media_proxy, line
381 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing append_hf, line 382 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing t_on_branch, line 383 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing t_on_reply, line 384 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing t_on_failure, line 385 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing t_relay, line 388 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing sl_reply_error, line 389 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing append_hf, line 430 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing xlog, line 431 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing t_was_cancelled, line
436 
Mar 20 14:56:22 [4818] DBG:core:fix_actions: fixing xlog, line 424 
Mar 20 14:56:22 [4818] DBG:core:probe_max_receive_buffer: getsockopt
SO_RCVBUF is initially 111616 
Mar 20 14:56:22 [4818] DBG:core:probe_max_receive_buffer: trying SO_RCVBUF:
223232 
Mar 20 14:56:22 [4818] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF;
set=223232,verify=262142 
((((POSTER REMOVED SOME REDUNDANT LINES HERE SETTING BUFFERS )))))))))

Mar 20 14:56:22 [4818] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF
has no effect 
Mar 20 14:56:22 [4818] INFO:core:probe_max_receive_buffer: using a UDP
receive buffer of 255 kb 
Mar 20 14:56:22 [4818] ERROR:core:tcp_init: bind(6, 0x8190ddc, 16) on
127.0.0.1:5060 : Address already in use 
Mar 20 14:56:22 [4818] DBG:xlog:destroy: destroy module... 
Mar 20 14:56:22 [4818] DBG:tm:tm_shutdown: tm_shutdown : start 
Mar 20 14:56:22 [4818] DBG:tm:unlink_timer_lists: emptying DELETE list 
Mar 20 14:56:22 [4818] DBG:tm:tm_shutdown: emptying hash table 
Mar 20 14:56:22 [4818] DBG:tm:tm_shutdown: releasing timers 
Mar 20 14:56:22 [4818] DBG:tm:tm_shutdown: removing semaphores 
Mar 20 14:56:22 [4818] DBG:tm:tm_shutdown: destroying callback lists 
Mar 20 14:56:22 [4818] DBG:tm:tm_shutdown: tm_shutdown : done 
Mar 20 14:56:22 [4818] DBG:core:shm_mem_destroy: 
Mar 20 14:56:22 [4818] DBG:core:shm_mem_destroy: destroying the shared
memory lock 
-- 
View this message in context: http://n2.nabble.com/Trouble-getting-mediaproxy-to-work-%28dialog-module%29-tp2506005p2511404.html
Sent from the OpenSIPS - Users mailing list archive at Nabble.com.




More information about the Users mailing list