Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

fork=no crashes opensips #445

Closed
bbuitenhuis opened this issue Mar 30, 2015 · 3 comments
Closed

fork=no crashes opensips #445

bbuitenhuis opened this issue Mar 30, 2015 · 3 comments
Assignees
@bogdan-iancu
Labels
bug
Milestone
2.1.4

Comments

@bbuitenhuis
Copy link
Contributor

Hello,

From the log:

Mar 30 21:28:40 [26115] DBG:core:count_init_children: 1 children are going to be inited

full log

Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/signaling.so
Mar 30 21:28:40 [26115] DBG:core:add_module_dependency: adding type 0 dependency signaling - (module tm)
Mar 30 21:28:40 [26115] DBG:core:add_module_dependency: adding type 0 dependency signaling - (module sl)
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/sl.so
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/tm.so
Mar 30 21:28:40 [26115] DBG:core:register_module: register_pv: tm
Mar 30 21:28:40 [26115] DBG:core:pv_add_extra: extra items list is not initialized
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: tm matches module tm
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <fr_timeout> in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: tm matches module tm
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <fr_inv_timeout> in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: tm matches module tm
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <restart_fr_on_each_reply> in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: tm matches module tm
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <onreply_avp_mode> in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/rr.so
Mar 30 21:28:40 [26115] DBG:core:register_module: register_pv: rr
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: rr matches module rr
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <append_fromtag> in module rr [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/maxfwd.so
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/sipmsgops.so
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/mi_fifo.so
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: mi_fifo matches module mi_fifo
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <fifo_name> in module mi_fifo [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: mi_fifo matches module mi_fifo
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <fifo_mode> in module mi_fifo [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/uri.so
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: uri matches module uri
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <use_uri_table> in module uri [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/db_mysql.so
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/usrloc.so
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: usrloc matches module usrloc
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <nat_bflag> in module usrloc [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: usrloc matches module usrloc
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <db_mode> in module usrloc [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/registrar.so
Mar 30 21:28:40 [26115] DBG:core:add_module_dependency: adding type 2 dependency registrar - (module usrloc)
Mar 30 21:28:40 [26115] DBG:core:add_module_dependency: adding type 2 dependency registrar - (module signaling)
Mar 30 21:28:40 [26115] DBG:core:add_module_dependency: adding type 0 dependency registrar - (module tm)
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: registrar matches module registrar
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <tcp_persistent_flag> in module registrar [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: registrar matches module registrar
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <received_avp> in module registrar [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/acc.so
Mar 30 21:28:40 [26115] DBG:core:add_module_dependency: adding type 2 dependency acc - (module tm)
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: acc matches module acc
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <early_media> in module acc [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: acc matches module acc
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <report_cancels> in module acc [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: acc matches module acc
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <detect_direction> in module acc [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: acc matches module acc
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <failed_transaction_flag> in module acc [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: acc matches module acc
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <log_flag> in module acc [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: acc matches module acc
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <log_missed_flag> in module acc [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/dialog.so
Mar 30 21:28:40 [26115] DBG:core:register_module: register_pv: dialog
Mar 30 21:28:40 [26115] DBG:core:add_module_dependency: adding type 2 dependency dialog - (module tm)
Mar 30 21:28:40 [26115] DBG:core:add_module_dependency: adding type 2 dependency dialog - (module rr)
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: dialog matches module dialog
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <dlg_match_mode> in module dialog [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: dialog matches module dialog
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <default_timeout> in module dialog [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: dialog matches module dialog
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <db_mode> in module dialog [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:add_modparam_dependencies: adding modparam dependencies:
Mar 30 21:28:40 [26115] DBG:core:add_modparam_dependencies: dependency found: dialog ---> ( sqldb module (null) )
Mar 30 21:28:40 [26115] DBG:core:add_module_dependency: adding type 2 dependency dialog - (sqldb module (null))
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: dialog matches module dialog
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <db_url> in module dialog [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/nathelper.so
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: nathelper matches module nathelper
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <natping_interval> in module nathelper [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:add_modparam_dependencies: adding modparam dependencies:
Mar 30 21:28:40 [26115] DBG:core:add_modparam_dependencies: dependency found: nathelper ---> ( module usrloc )
Mar 30 21:28:40 [26115] DBG:core:add_module_dependency: adding type 2 dependency nathelper - (module usrloc)
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: nathelper matches module nathelper
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <ping_nated_only> in module nathelper [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: nathelper matches module nathelper
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <received_avp> in module nathelper [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:load_module: loading module /usr/local//lib64/opensips/modules/rtpproxy.so
Mar 30 21:28:40 [26115] DBG:core:add_module_dependency: adding type 2 dependency rtpproxy - (module tm)
Mar 30 21:28:40 [26115] DBG:core:add_module_dependency: adding type 2 dependency rtpproxy - (module dialog)
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: rtpproxy matches module rtpproxy
Mar 30 21:28:40 [26115] DBG:core:set_mod_param_regex: found <rtpproxy_sock> in module rtpproxy [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <nat_uac_test>(1) in module nathelper [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <fix_nated_register>(0) in module nathelper [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <fix_nated_contact>(0) in module nathelper [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <mf_process_maxfwd_header>(1) in module maxfwd [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <has_totag>(0) in module uri [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <loose_route>(0) in module rr [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:pv_lookup_spec_name: found in extra list [DLG_status]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <validate_dialog>(0) in module dialog [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <record_route>(0) in module rr [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <check_route_param>(1) in module rr [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <t_check_trans>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <t_relay>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <t_check_trans>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <t_relay>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <t_check_trans>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <loose_route>(0) in module rr [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <record_route>(0) in module rr [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <create_dialog>(1) in module dialog [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <append_hf>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <save>(1) in module registrar [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <sl_reply_error>(0) in module sl [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <lookup>(2) in module registrar [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <t_newtran>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <t_reply>(2) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <rtpproxy_offer>(1) in module rtpproxy [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <t_on_branch>(1) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <t_on_reply>(1) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <t_on_failure>(1) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <add_rr_param>(1) in module rr [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <t_relay>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <nat_uac_test>(1) in module nathelper [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <fix_nated_contact>(0) in module nathelper [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <rtpproxy_answer>(1) in module rtpproxy [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <t_was_cancelled>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:pv_lookup_spec_name: found in extra list [DLG_dir]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <acc_log_request>(1) in module acc [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] INFO:core:fix_poll_method: using epoll_lt as the IO watch method (auto detected)
Mar 30 21:28:40 [26115] DBG:core:socket2str: <udp:XXX.XXX.XXX.XXX:5060>
Listening on
             udp: XXX.XXX.XXX.XXX [XXX.XXX.XXX.XXX]:5060
Aliases:

Mar 30 21:28:40 [26115] WARNING:core:main: no fork mode
Mar 30 21:28:40 [26115] INFO:core:shm_mem_init: allocating SHM block
Mar 30 21:28:40 [26115] DBG:core:fm_malloc_init: F_OPTIMIZE=16384, /ROUNDTO=2048
Mar 30 21:28:40 [26115] DBG:core:fm_malloc_init: F_HASH_SIZE=2099, fm_block size=33648
Mar 30 21:28:40 [26115] DBG:core:fm_malloc_init: params (0x7f80dde51000, 33554432), start=0x7f80dde51000
Mar 30 21:28:40 [26115] DBG:core:shm_mem_init_mallocs: success
Mar 30 21:28:40 [26115] DBG:core:init_stats_collector: statistics manager successfully initialized
Mar 30 21:28:40 [26115] DBG:core:set_core_dump: core dump limits set to 1
Mar 30 21:28:40 [26115] NOTICE:core:main: version: opensips 2.2-dev (x86_64/linux)
Mar 30 21:28:40 [26115] INFO:core:main: using 32 Mb shared memory
Mar 30 21:28:40 [26115] INFO:core:main: using 2 Mb private memory per process
Mar 30 21:28:40 [26115] DBG:core:__search_avp_map: looking for [serial_branch] avp  - found -1
Mar 30 21:28:40 [26115] DBG:core:new_avp_alias: added alias serial_branch with id 1
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_CORE_THRESHOLD(0)>
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_CORE_SHM_THRESHOLD(1)>
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_CORE_PKG_THRESHOLD(2)>
Mar 30 21:28:40 [26115] DBG:core:solve_module_dependencies: solving dependency rtpproxy -> module dialog
Mar 30 21:28:40 [26115] DBG:core:solve_module_dependencies: solving dependency rtpproxy -> module tm
Mar 30 21:28:40 [26115] DBG:core:solve_module_dependencies: solving dependency nathelper -> module usrloc
Mar 30 21:28:40 [26115] DBG:core:solve_module_dependencies: solving dependency dialog -> sqldb module
Mar 30 21:28:40 [26115] DBG:core:solve_module_dependencies: solving dependency dialog -> module rr
Mar 30 21:28:40 [26115] DBG:core:solve_module_dependencies: solving dependency dialog -> module tm
Mar 30 21:28:40 [26115] DBG:core:solve_module_dependencies: solving dependency acc -> module tm
Mar 30 21:28:40 [26115] DBG:core:solve_module_dependencies: solving dependency registrar -> module tm
Mar 30 21:28:40 [26115] DBG:core:solve_module_dependencies: solving dependency registrar -> module signaling
Mar 30 21:28:40 [26115] DBG:core:solve_module_dependencies: solving dependency registrar -> module usrloc
Mar 30 21:28:40 [26115] DBG:core:solve_module_dependencies: solving dependency signaling -> module sl
Mar 30 21:28:40 [26115] DBG:core:solve_module_dependencies: solving dependency signaling -> module tm
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module tm
Mar 30 21:28:40 [26115] INFO:tm:mod_init: TM - initializing...
Mar 30 21:28:40 [26115] DBG:core:get_flag_id_by_name: Flag name is null!
Mar 30 21:28:40 [26115] DBG:tm:init_callid: Call-ID initialization: '7329626a7cb45817'
Mar 30 21:28:40 [26115] DBG:tm:lock_initialize: lock initialization started
Mar 30 21:28:40 [26115] DBG:tm:tm_init_timers: creating 0 parallel timer structures
Mar 30 21:28:40 [26115] DBG:tm:mod_init: timer set shift is 3
Mar 30 21:28:40 [26115] DBG:core:MD5StringArray: MD5 calculated: 90e3c3598c2bc07913116f02e0163d26
Mar 30 21:28:40 [26115] DBG:core:MD5StringArray: MD5 calculated: c11a098b3e52c331e0014f1ac95258ca
Mar 30 21:28:40 [26115] DBG:core:register_pv_context: Registered new context: request / 0x7f80e2b96e9a
Mar 30 21:28:40 [26115] DBG:core:register_pv_context: Context not found
Mar 30 21:28:40 [26115] DBG:core:register_pv_context: Registered new context: reply / 0x7f80e2b97375
Mar 30 21:28:40 [26115] DBG:core:register_pv_context: Context not found
Mar 30 21:28:40 [26115] DBG:core:add_pv_context: Registered new context: reply
Mar 30 21:28:40 [26115] DBG:core:__search_avp_map: looking for [uac_ctx] avp  - found -1
Mar 30 21:28:40 [26115] DBG:core:new_avp_alias: added alias uac_ctx with id 2
Mar 30 21:28:40 [26115] DBG:core:init_mod: registering stats for tm
Mar 30 21:28:40 [26115] DBG:core:init_mod: register MI for tm
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module sl
Mar 30 21:28:40 [26115] INFO:sl:mod_init: Initializing StateLess engine
Mar 30 21:28:40 [26115] DBG:core:MD5StringArray: MD5 calculated: 1953e65150df62404d32451291f40902
Mar 30 21:28:40 [26115] DBG:core:init_mod: registering stats for sl
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module signaling
Mar 30 21:28:40 [26115] NOTICE:signaling:mod_init: initializing module ...
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <load_tm>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <load_sl>(0) in module sl [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module rr
Mar 30 21:28:40 [26115] INFO:rr:mod_init: rr - initializing
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module maxfwd
Mar 30 21:28:40 [26115] INFO:maxfwd:mod_init: initializing...
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module sipmsgops
Mar 30 21:28:40 [26115] INFO:sipmsgops:mod_init: initializing...
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module mi_fifo
Mar 30 21:28:40 [26115] DBG:mi_fifo:mi_mod_init: testing fifo existance ...
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module uri
Mar 30 21:28:40 [26115] DBG:uri:mod_init: initializing
Mar 30 21:28:40 [26115] DBG:core:init_mod: registering stats for uri
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module db_mysql
Mar 30 21:28:40 [26115] DBG:db_mysql:mysql_mod_init: mysql: MySQL client version is 5.5.41
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_MYSQL_CONNECTION(3)>
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module usrloc
Mar 30 21:28:40 [26115] DBG:usrloc:mod_init: initializing
Mar 30 21:28:40 [26115] INFO:usrloc:ul_init_locks: locks array size 512
Mar 30 21:28:40 [26115] DBG:core:get_flag_id_by_name: New flag: [ NAT : 0 ][2]
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_UL_AOR_INSERT(4)>
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_UL_AOR_DELETE(5)>
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_UL_CONTACT_INSERT(6)>
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_UL_CONTACT_DELETE(7)>
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_UL_CONTACT_UPDATE(8)>
Mar 30 21:28:40 [26115] DBG:core:init_mod: registering stats for usrloc
Mar 30 21:28:40 [26115] DBG:core:init_mod: register MI for usrloc
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module registrar
Mar 30 21:28:40 [26115] INFO:registrar:mod_init: initializing...
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <load_sig>(1) in module signaling [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <load_tm>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:__search_avp_map: looking for [received_nh] avp  - found -1
Mar 30 21:28:40 [26115] DBG:core:new_avp_alias: added alias received_nh with id 3
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <ul_bind_usrloc>(1) in module usrloc [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:get_flag_id_by_name: New flag: [ TCP_PERSISTENT : 0 ][0]
Mar 30 21:28:40 [26115] DBG:core:init_mod: registering stats for registrar
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module acc
Mar 30 21:28:40 [26115] INFO:acc:mod_init: initializing...
Mar 30 21:28:40 [26115] DBG:core:get_flag_id_by_name: New flag: [ ACC_FAILED : 1 ][0]
Mar 30 21:28:40 [26115] DBG:core:get_flag_id_by_name: Flag name is null!
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <load_tm>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <load_dlg>(0) in module dialog [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:get_flag_id_by_name: New flag: [ ACC_DO : 2 ][0]
Mar 30 21:28:40 [26115] DBG:core:get_flag_id_by_name: New flag: [ ACC_MISSED : 3 ][0]
Mar 30 21:28:40 [26115] DBG:core:get_flag_id_by_name: Flag name is null!
Mar 30 21:28:40 [26115] DBG:core:get_flag_id_by_name: Flag name is null!
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_ACC_EVENT(9)>
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_ACC_CDR(10)>
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_ACC_MISSED_EVENT(11)>
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module dialog
Mar 30 21:28:40 [26115] INFO:dialog:mod_init: Dialog module - initializing
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_DLG_STATE_CHANGED(12)>
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <load_tm>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <load_rr>(0) in module rr [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_mod_export: found <db_bind_api> in module db_mysql [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:db_bind_mod: using db bind api for db_mysql
Mar 30 21:28:40 [26115] DBG:core:db_do_init: connection 0x7f80e3203bb8 not found in pool
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/opensips
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_connect: server version is 5.5.41-0+wheezy1
Mar 30 21:28:40 [26115] DBG:core:db_do_init: connection 0x7f80e3203bb8 inserted in pool as 0x7f80e3203d60
Mar 30 21:28:40 [26115] DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 30 21:28:40 [26115] DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f80e32043a0
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: 1 columns returned from the query
Mar 30 21:28:40 [26115] DBG:core:db_allocate_columns: allocate 28 bytes for result columns at 0x7f80e32043e8
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e32043f0)[0]=[table_version]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 30 21:28:40 [26115] DBG:core:db_allocate_rows: allocate 48 bytes for result rows and values at 0x7f80e3204420
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_str2val: converting INT [10]
Mar 30 21:28:40 [26115] DBG:core:db_free_columns: freeing result columns at 0x7f80e32043e8
Mar 30 21:28:40 [26115] DBG:core:db_free_rows: freeing 1 rows
Mar 30 21:28:40 [26115] DBG:core:db_free_row: freeing row values at 0x7f80e3204430
Mar 30 21:28:40 [26115] DBG:core:db_free_rows: freeing rows at 0x7f80e3204420
Mar 30 21:28:40 [26115] DBG:core:db_free_result: freeing result set at 0x7f80e32043a0
Mar 30 21:28:40 [26115] DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 30 21:28:40 [26115] DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f80e32043a0
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: 26 columns returned from the query
Mar 30 21:28:40 [26115] DBG:core:db_allocate_columns: allocate 728 bytes for result columns at 0x7f80e3204468
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204538)[0]=[dlg_id]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_BIGINT result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204548)[1]=[callid]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204558)[2]=[from_uri]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204568)[3]=[from_tag]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204578)[4]=[to_uri]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204588)[5]=[to_tag]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204598)[6]=[start_time]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e32045a8)[7]=[state]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e32045b8)[8]=[timeout]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e32045c8)[9]=[caller_cseq]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e32045d8)[10]=[callee_cseq]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e32045e8)[11]=[caller_route_set]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_BLOB result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e32045f8)[12]=[callee_route_set]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_BLOB result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204608)[13]=[caller_contact]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204618)[14]=[callee_contact]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204628)[15]=[caller_sock]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204638)[16]=[callee_sock]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204648)[17]=[vars]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_BLOB result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204658)[18]=[profiles]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_BLOB result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204668)[19]=[script_flags]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204678)[20]=[caller_ping_cseq]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204688)[21]=[callee_ping_cseq]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e3204698)[22]=[flags]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e32046a8)[23]=[mangled_from_uri]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e32046b8)[24]=[mangled_to_uri]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f80e32046c8)[25]=[module_flags]
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_fetch_result: no rows returned from the query
Mar 30 21:28:40 [26115] DBG:dialog:load_dialog_info_from_db: loading information from database for 0 dialogs
Mar 30 21:28:40 [26115] DBG:core:db_free_columns: freeing result columns at 0x7f80e3204468
Mar 30 21:28:40 [26115] DBG:core:db_free_rows: freeing 0 rows
Mar 30 21:28:40 [26115] DBG:core:db_free_result: freeing result set at 0x7f80e32043a0
Mar 30 21:28:40 [26115] DBG:core:pool_remove: removing connection from the pool
Mar 30 21:28:40 [26115] DBG:core:init_mod: registering stats for dialog
Mar 30 21:28:40 [26115] DBG:core:init_mod: register MI for dialog
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module nathelper
Mar 30 21:28:40 [26115] DBG:core:__search_avp_map: looking for [received_nh] avp  - found 3
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <ul_bind_usrloc>(1) in module usrloc [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:get_flag_id_by_name: Flag name is null!
Mar 30 21:28:40 [26115] DBG:core:init_mod: register MI for nathelper
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module rtpproxy
Mar 30 21:28:40 [26115] DBG:rtpproxy:rtpproxy_add_rtpproxy_set: Adding proxy in setid 0 [udp:localhost:12221]
Mar 30 21:28:40 [26115] DBG:rtpproxy:rtpproxy_add_rtpproxy_set: List not found ((nil)) for id 0Mar 30 21:28:40 [26115] DBG:rtpproxy:add_rtpproxy_socks: url is udp:localhost:12221, len is 19
Mar 30 21:28:40 [26115] DBG:rtpproxy:select_rtpp_set: Looking for set_id 0
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <load_dlg>(0) in module dialog [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:find_cmd_export_t: found <load_tm>(0) in module tm [/usr/local//lib64/opensips/modules/]
Mar 30 21:28:40 [26115] DBG:core:pv_lookup_spec_name: found in extra list [bavp]
Mar 30 21:28:40 [26115] DBG:core:__search_avp_map: looking for [5589965] avp  - found -1
Mar 30 21:28:40 [26115] DBG:core:new_avp_alias: added alias 5589965 with id 4
Mar 30 21:28:40 [26115] DBG:core:pv_lookup_spec_name: found in extra list [bavp]
Mar 30 21:28:40 [26115] DBG:core:__search_avp_map: looking for [5589966] avp  - found -1
Mar 30 21:28:40 [26115] DBG:core:new_avp_alias: added alias 5589966 with id 5
Mar 30 21:28:40 [26115] DBG:core:pv_lookup_spec_name: found in extra list [bavp]
Mar 30 21:28:40 [26115] DBG:core:__search_avp_map: looking for [5589967] avp  - found -1
Mar 30 21:28:40 [26115] DBG:core:new_avp_alias: added alias 5589967 with id 6
Mar 30 21:28:40 [26115] INFO:core:evi_publish_event: Registered event <E_RTPPROXY_STATUS(13)>
Mar 30 21:28:40 [26115] DBG:core:init_mod: register MI for rtpproxy
Mar 30 21:28:40 [26115] DBG:core:init_mod: initializing module proto_udp
Mar 30 21:28:40 [26115] INFO:core:mod_init: initializing UDP-plain protocol
Mar 30 21:28:40 [26115] DBG:core:count_module_procs: modules require 1 extra processes
Mar 30 21:28:40 [26115] DBG:core:init_pkg_stats: setting stats for 4 processes
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing nat_uac_test, /usr/local//etc/opensips/opensips.cfg:170
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:171
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x80
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for method [REGISTER/128]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing fix_nated_register, /usr/local//etc/opensips/opensips.cfg:172
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing fix_nated_contact, /usr/local//etc/opensips/opensips.cfg:175
Mar 30 21:28:40 [26115] DBG:core:get_flag_id_by_name: New flag: [ NAT : 4 ][0]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing mf_process_maxfwd_header, /usr/local//etc/opensips/opensips.cfg:181
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing sl_send_reply, /usr/local//etc/opensips/opensips.cfg:182
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing has_totag, /usr/local//etc/opensips/opensips.cfg:186
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing loose_route, /usr/local//etc/opensips/opensips.cfg:189
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing validate_dialog, /usr/local//etc/opensips/opensips.cfg:192
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:197
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x8
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for method [BYE/8]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:200
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x1
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for method [INVITE/1]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing record_route, /usr/local//etc/opensips/opensips.cfg:204
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing check_route_param, /usr/local//etc/opensips/opensips.cfg:207
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:215
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x4
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for method [ACK/4]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing t_check_trans, /usr/local//etc/opensips/opensips.cfg:216
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing t_relay, /usr/local//etc/opensips/opensips.cfg:219
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing sl_send_reply, /usr/local//etc/opensips/opensips.cfg:227
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:233
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x2
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for method [CANCEL/2]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing t_check_trans, /usr/local//etc/opensips/opensips.cfg:235
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing t_relay, /usr/local//etc/opensips/opensips.cfg:236
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing t_check_trans, /usr/local//etc/opensips/opensips.cfg:240
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:242
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x80
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for method [REGISTER/128]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing send_reply, /usr/local//etc/opensips/opensips.cfg:252
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing loose_route, /usr/local//etc/opensips/opensips.cfg:260
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:263
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x4
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for method [ACK/4]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing sl_send_reply, /usr/local//etc/opensips/opensips.cfg:264
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:269
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x180
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for methods [REGISTER,MESSAGE/384]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing record_route, /usr/local//etc/opensips/opensips.cfg:270
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:273
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x1
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for method [INVITE/1]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing create_dialog, /usr/local//etc/opensips/opensips.cfg:276
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing send_reply, /usr/local//etc/opensips/opensips.cfg:277
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing append_hf, /usr/local//etc/opensips/opensips.cfg:286
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:293
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x2200
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for methods [PUBLISH,SUBSCRIBE/8704]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing sl_send_reply, /usr/local//etc/opensips/opensips.cfg:295
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:299
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x80
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for method [REGISTER/128]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing save, /usr/local//etc/opensips/opensips.cfg:305
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing sl_reply_error, /usr/local//etc/opensips/opensips.cfg:306
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing sl_send_reply, /usr/local//etc/opensips/opensips.cfg:313
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing lookup, /usr/local//etc/opensips/opensips.cfg:324
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing t_newtran, /usr/local//etc/opensips/opensips.cfg:327
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing t_reply, /usr/local//etc/opensips/opensips.cfg:328
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:342
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x1
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for method [INVITE/1]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing rtpproxy_offer, /usr/local//etc/opensips/opensips.cfg:345
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing t_on_branch, /usr/local//etc/opensips/opensips.cfg:348
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing t_on_reply, /usr/local//etc/opensips/opensips.cfg:349
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing t_on_failure, /usr/local//etc/opensips/opensips.cfg:350
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing add_rr_param, /usr/local//etc/opensips/opensips.cfg:354
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing t_relay, /usr/local//etc/opensips/opensips.cfg:357
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing send_reply, /usr/local//etc/opensips/opensips.cfg:358
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing nat_uac_test, /usr/local//etc/opensips/opensips.cfg:372
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing fix_nated_contact, /usr/local//etc/opensips/opensips.cfg:373
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing rtpproxy_answer, /usr/local//etc/opensips/opensips.cfg:375
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing t_was_cancelled, /usr/local//etc/opensips/opensips.cfg:381
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing is_method, /usr/local//etc/opensips/opensips.cfg:398
Mar 30 21:28:40 [26115] DBG:core:parse_methods: methods 0x8
Mar 30 21:28:40 [26115] DBG:sipmsgops:fixup_method: using id for method [BYE/8]
Mar 30 21:28:40 [26115] DBG:core:fix_actions: fixing acc_log_request, /usr/local//etc/opensips/opensips.cfg:400
Mar 30 21:28:40 [26115] DBG:core:create_status_pipe: pipe created ? rc = 0, errno = Success
Mar 30 21:28:40 [26115] DBG:core:start_module_procs: forking process "MI FIFO"/0 for module mi_fifo
Mar 30 21:28:40 [26115] DBG:core:internal_fork: forking new process "MI FIFO"
Mar 30 21:28:40 [26115] DBG:core:internal_fork: forking new process "time_keeper"
Mar 30 21:28:40 [26117] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=tm
Mar 30 21:28:40 [26115] DBG:core:internal_fork: forking new process "timer"
Mar 30 21:28:40 [26117] DBG:tm:child_init_callid: callid: '7329626a7cb45817-26117@XXX.XXX.XXX.XXX'
Mar 30 21:28:40 [26118] DBG:core:run_timer_process_jif: tv = 0, 10000 , m=10, mu=10
Mar 30 21:28:40 [26117] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=mi_fifo
Mar 30 21:28:40 [26117] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=uri
Mar 30 21:28:40 [26115] DBG:core:init_mod_child: type=CHILD, rank=1, module=tm
Mar 30 21:28:40 [26115] DBG:tm:child_init_callid: callid: '7329626a7cb45817-26115@XXX.XXX.XXX.XXX'
Mar 30 21:28:40 [26115] DBG:core:init_mod_child: type=CHILD, rank=1, module=mi_fifo
Mar 30 21:28:40 [26119] DBG:core:run_timer_process: tv = 0, 100000 , m=10
Mar 30 21:28:40 [26117] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=usrloc
Mar 30 21:28:40 [26115] DBG:core:init_mod_child: type=CHILD, rank=1, module=uri
Mar 30 21:28:40 [26115] DBG:core:init_mod_child: type=CHILD, rank=1, module=usrloc
Mar 30 21:28:40 [26115] DBG:core:init_mod_child: type=CHILD, rank=1, module=registrar
Mar 30 21:28:40 [26115] DBG:core:init_mod_child: type=CHILD, rank=1, module=acc
Mar 30 21:28:40 [26115] DBG:core:init_mod_child: type=CHILD, rank=1, module=dialog
Mar 30 21:28:40 [26115] DBG:core:db_do_init: connection 0x7f80e31f4c48 not found in pool
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/opensips
Mar 30 21:28:40 [26117] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=registrar
Mar 30 21:28:40 [26117] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=acc
Mar 30 21:28:40 [26117] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=dialog
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 30 21:28:40 [26115] DBG:db_mysql:db_mysql_connect: server version is 5.5.41-0+wheezy1
Mar 30 21:28:40 [26115] DBG:core:db_do_init: connection 0x7f80e31f4c48 inserted in pool as 0x7f80e3203518
Mar 30 21:28:40 [26115] DBG:core:init_mod_child: type=CHILD, rank=1, module=rtpproxy
Mar 30 21:28:40 [26115] DBG:rtpproxy:connect_rtpproxies: [RTPProxy] set list 0x7f80de100488
Mar 30 21:28:40 [26115] DBG:rtpproxy:connect_rtpproxies: [Re]connecting sockets (1 > 0)
Mar 30 21:28:40 [26117] DBG:core:db_do_init: connection 0x7f80e31f4c48 not found in pool
Mar 30 21:28:40 [26115] DBG:rtpproxy:connect_rtpproxies: connected localhost:12221
Mar 30 21:28:40 [26117] DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/opensips
Mar 30 21:28:40 [26115] INFO:rtpproxy:rtpp_test: rtp proxy <udp:localhost:12221> found, support for it enabled
Mar 30 21:28:40 [26117] DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 30 21:28:40 [26117] DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 30 21:28:40 [26117] DBG:db_mysql:db_mysql_connect: server version is 5.5.41-0+wheezy1
Mar 30 21:28:40 [26117] DBG:core:db_do_init: connection 0x7f80e31f4c48 inserted in pool as 0x7f80e3203518
Mar 30 21:28:40 [26115] DBG:rtpproxy:raise_rtpproxy_event: no event sent
Mar 30 21:28:40 [26115] DBG:rtpproxy:connect_rtpproxies: successfully updated proxy sets
Mar 30 21:28:40 [26115] DBG:core:main_loop: waiting for status code from children
Mar 30 21:28:40 [26115] DBG:core:count_init_children: 1 children are going to be inited
Mar 30 21:28:40 [26117] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=rtpproxy
Mar 30 21:28:40 [26117] DBG:core:send_status_code: send 0 ? rc = 1 , errno=Success
Mar 30 21:28:40 [26115] DBG:core:wait_status_code: read code 0 ? rc = 1, errno=Success
Mar 30 21:28:40 [26115] INFO:core:cleanup: cleanup
Mar 30 21:28:40 [26118] INFO:core:sig_usr: signal 15 received
Mar 30 21:28:40 [26115] DBG:core:pool_remove: removing connection from the pool
Mar 30 21:28:40 [26117] INFO:core:sig_usr: signal 15 received
Mar 30 21:28:40 [26119] INFO:core:sig_usr: signal 15 received
Mar 30 21:28:40 [26115] DBG:core:handle_sigs: status = 0
Mar 30 21:28:40 [26115] INFO:core:handle_sigs: child process 26118 exited normally, status=0
Mar 30 21:28:40 [26115] INFO:core:handle_sigs: terminating due to SIGCHLD
Mar 30 21:28:40 [26115] INFO:core:cleanup: cleanup
Mar 30 21:28:40 [26115] DBG:core:pool_remove: removing connection from the pool
Mar 30 21:28:40 [26115] ERROR:core:pool_remove: weird, connection not found in the pool
Mar 30 21:28:40 [26115] DBG:tm:tm_shutdown: tm_shutdown : start
Mar 30 21:28:40 [26115] DBG:tm:unlink_timer_lists: emptying DELETE list for set 0
Mar 30 21:28:40 [26115] DBG:tm:tm_shutdown: emptying hash table
Mar 30 21:28:40 [26115] DBG:tm:tm_shutdown: releasing timers
Mar 30 21:28:40 [26115] DBG:tm:tm_shutdown: removing semaphores
Mar 30 21:28:40 [26115] DBG:tm:tm_shutdown: destroying callback lists
Mar 30 21:28:40 [26115] DBG:tm:tm_shutdown: tm_shutdown : done
Mar 30 21:29:40 [26115] CRITICAL:core:sig_alarm_abort: BUG - shutdown timeout triggered, dying...Aborted (core dumped)

Core dump:

#0  0x00000000004460d9 in handle_sigs () at main.c:457
        chld = 25806
        chld_status = 0
        overall_status = 0
        i = 0
        do_exit = <optimized out>
        __FUNCTION__ = "handle_sigs"
#1  0x0000000000446c45 in sig_usr (signo=17) at main.c:532
        status = <optimized out>
        pid = <optimized out>
        __FUNCTION__ = "sig_usr"
#2  <signal handler called>
No symbol table info available.
#3  0x00007ff5b3ea91f7 in munmap () from /lib/x86_64-linux-gnu/libc.so.6
No symbol table info available.
#4  0x0000000000518e8b in shm_mem_destroy () at mem/shm_mem.c:442
        __FUNCTION__ = "shm_mem_destroy"
#5  0x0000000000445b97 in cleanup (show_status=show_status@entry=0) at main.c:333
        __FUNCTION__ = "cleanup"
#6  0x00000000004193f1 in main (argc=<optimized out>, argv=<optimized out>) at main.c:1284
        cfg_log_stderr = <optimized out>
        cfg_stream = <optimized out>
        c = <optimized out>
        r = <optimized out>
        tmp = 0x1 <Address 0x1 out of bounds>
        tmp_len = <optimized out>
        port = <optimized out>
        proto = <optimized out>
        options = 0x5cfae8 "f:cCm:M:b:l:n:N:rRvdDFETSVhw:t:u:g:P:G:W:o:"
        ret = -1
        seed = 2
        rfd = <optimized out>
        __FUNCTION__ = "main"
@bbuitenhuis bbuitenhuis changed the title default config (using osipsconfig) crashes opensips 2.x: default config (using osipsconfig) crashes opensips Mar 30, 2015
@bbuitenhuis bbuitenhuis changed the title 2.x: default config (using osipsconfig) crashes opensips 2.x: fork=no crashes opensips Mar 30, 2015
@bbuitenhuis
Copy link
Contributor Author

Problem only occurs with
fork=no

@razvancrainea
Copy link
Member

Is that the only core file you receive? Do you have pid-named core files?

@razvancrainea
Copy link
Member

After checking the code, this is a limitation of the new design - it cannot start in no fork mode.
We'll working on a solution for this.

@bogdan-iancu bogdan-iancu added this to the 2.1 milestone Apr 1, 2015
@bogdan-iancu bogdan-iancu changed the title 2.x: fork=no crashes opensips fork=no crashes opensips Apr 1, 2015
bogdan-iancu added a commit that referenced this issue Apr 14, 2015
@bogdan-iancu
Fix dont_fork mode.
1a1d72c 
Closes #445.

(cherry picked from commit dbf2aec)
@bogdan-iancu bogdan-iancu mentioned this issue Apr 14, 2015
Closed
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@bogdan-iancu bogdan-iancu

Labels
bug
Projects
None yet
Milestone
2.1.4
Development

No branches or pull requests
3 participants
@razvancrainea @bogdan-iancu @bbuitenhuis