No subject
Tue Nov 6 14:49:37 UTC 2007
set to 4294967295
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
DEBUG:add_avp_galias: registering <serial_branch> for avp id <16725044>
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: INFO: statistics
manager successfully initialized
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: init_mod:
dbtext
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: init_mod:
sl
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: StateLess module
- initializing
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: MD5
calculated: 329cfeaa6ded039da25ff8cbb8668bd2
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG:
register_stats: sl
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: init_mod:
tm
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: TM -
initializing...
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: Call-ID
initialization: '6feb8bff'
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG:
lock_initialize: lock initialization started
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: MD5
calculated: 533cb9e91f4b999cf76861cbb9ed54ed
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: MD5
calculated: fa997f81440371de71ab448ebdb9af56
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG:
register_stats: tm
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG:
register_mi: tm
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: init_mod:
rr
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: rr -
initializing
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: init_mod:
maxfwd
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: Maxfwd module-
initializing
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: init_mod:
usrloc
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: usrloc -
initializing
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
INFO:ul_init_locks: locks array size 512
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG:
register_stats: usrloc
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG:
register_mi: usrloc
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: init_mod:
registrar
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: registrar -
initializing
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
find_cmd_export_t: found <load_sl>(0) in module sl
[/usr/local/lib/openser/modules/]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
find_cmd_export_t: found <ul_bind_usrloc>(1) in module usrloc
[/usr/local/lib/openser/modules/]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG:
register_stats: registrar
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: init_mod:
textops
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: TextOPS -
initializing
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: init_mod:
mi_fifo
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DBG: mi_fifo:
mi_mod_init: testing fifo existance ...
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DEBUG: init_mod:
lcr
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
DEBUG:lcr:mod_init: initializing
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: find_mod_export:
found <db_use_table> in module dbtext [/usr/local/lib/openser/modules/]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: find_mod_export:
found <db_init> in module dbtext [/usr/local/lib/openser/modules/]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: find_mod_export:
found <db_close> in module dbtext [/usr/local/lib/openser/modules/]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: find_mod_export:
found <db_query> in module dbtext [/usr/local/lib/openser/modules/]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: find_mod_export:
<db_fetch_result> in module dbtext not found
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: find_mod_export:
found <db_raw_query> in module dbtext [/usr/local/lib/openser/modules/]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: find_mod_export:
found <db_free_result> in module dbtext [/usr/local/lib/openser/modules/]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: find_mod_export:
found <db_insert> in module dbtext [/usr/local/lib/openser/modules/]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: find_mod_export:
found <db_delete> in module dbtext [/usr/local/lib/openser/modules/]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: find_mod_export:
found <db_update> in module dbtext [/usr/local/lib/openser/modules/]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: find_mod_export:
<db_replace> in module dbtext not found
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: find_mod_export:
<db_last_inserted_id> in module dbtext not found
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_vname:
parsing [$avp(i:704)]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_name:
name [i:704] index [0]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_vname:
avp [i:704]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_vname:
parsing [$avp(i:709)]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_name:
name [i:709] index [0]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_vname:
avp [i:709]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_vname:
parsing [$avp(i:500)]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_name:
name [i:500] index [0]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_vname:
avp [i:500]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_vname:
parsing [$avp(i:711)]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_name:
name [i:711] index [0]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_vname:
avp [i:711]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_vname:
parsing [$avp(i:302)]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_name:
name [i:302] index [0]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: xl_parse_vname:
avp [i:302]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
DBT:dbt_cache_get_db: looking for db /usr/local/etc/openser/dbtext!
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
DBT:dbt_cache_get_db: new db!
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
DBT:dbt_load_file: request for table [version]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
DBT:dbt_load_file: db is [/usr/local/etc/openser/dbtext]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
DBT:dbt_load_file: loading file [/usr/local/etc/openser/dbtext/version]
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
DBT:dbt_table_new: mtime is 1184594731
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DBT: column[0]
is STR!
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DBT: column[1]
is INT!
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
DTB:dbt_db_get_table: version
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]: DBT:dbt_query:
new res with 1 cols
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
DBT:dbt_result_new: new res with 1 cols
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
DBT:dbt_cache_get_db: looking for db /usr/local/etc/openser/dbtext!
Jul 16 10:10:24 localhost /usr/local/sbin/openser[16743]:
DBT:dbt_cache_get_db: db already cached!
# gdb --pid=16743 /usr/local/sbin/openser
GNU gdb 6.4.90-debian
Copyright (C) 2006 Free Software Foundation, Inc.
GDB is free software, covered by the GNU General Public License, and you
are
welcome to change it and/or distribute copies of it under certain
conditions.
Type "show copying" to see the conditions.
There is absolutely no warranty for GDB. Type "show warranty" for
details.
This GDB was configured as "i486-linux-gnu"...Using host libthread_db
library "/lib/tls/libthread_db.so.1".
Attaching to program: /usr/local/sbin/openser, process 16743
Reading symbols from /lib/tls/libdl.so.2...done.
Loaded symbols for /lib/tls/libdl.so.2
Reading symbols from /lib/tls/libresolv.so.2...done.
Loaded symbols for /lib/tls/libresolv.so.2
Reading symbols from /lib/tls/libc.so.6...done.
Loaded symbols for /lib/tls/libc.so.6
Reading symbols from /lib/ld-linux.so.2...done.
Loaded symbols for /lib/ld-linux.so.2
Reading symbols from /usr/local/lib/openser/modules/dbtext.so...done.
Loaded symbols for /usr/local/lib/openser/modules/dbtext.so
Reading symbols from /usr/local/lib/openser/modules/sl.so...done.
Loaded symbols for /usr/local/lib/openser/modules/sl.so
Reading symbols from /usr/local/lib/openser/modules/tm.so...done.
Loaded symbols for /usr/local/lib/openser/modules/tm.so
Reading symbols from /usr/local/lib/openser/modules/rr.so...done.
Loaded symbols for /usr/local/lib/openser/modules/rr.so
Reading symbols from /usr/local/lib/openser/modules/maxfwd.so...done.
Loaded symbols for /usr/local/lib/openser/modules/maxfwd.so
Reading symbols from /usr/local/lib/openser/modules/usrloc.so...done.
Loaded symbols for /usr/local/lib/openser/modules/usrloc.so
Reading symbols from /usr/local/lib/openser/modules/registrar.so...done.
Loaded symbols for /usr/local/lib/openser/modules/registrar.so
Reading symbols from /usr/local/lib/openser/modules/textops.so...done.
Loaded symbols for /usr/local/lib/openser/modules/textops.so
Reading symbols from /usr/local/lib/openser/modules/mi_fifo.so...done.
Loaded symbols for /usr/local/lib/openser/modules/mi_fifo.so
Reading symbols from /usr/local/lib/openser/modules/lcr.so...done.
Loaded symbols for /usr/local/lib/openser/modules/lcr.so
Reading symbols from /lib/tls/libnss_files.so.2...done.
Loaded symbols for /lib/tls/libnss_files.so.2
Reading symbols from /lib/libnss_mdns4_minimal.so.2...done.
Loaded symbols for /lib/libnss_mdns4_minimal.so.2
Reading symbols from /lib/tls/libnss_dns.so.2...done.
Loaded symbols for /lib/tls/libnss_dns.so.2
0x400f7b67 in sched_yield () from /lib/tls/libc.so.6
(gdb)
(gdb)
(gdb)
(gdb) bt
#0 0x400f7b67 in sched_yield () from /lib/tls/libc.so.6
#1 0x4001f0dc in get_lock () from
/usr/local/lib/openser/modules/dbtext.so
#2 0x4001fa4c in dbt_db_del_table () from
/usr/local/lib/openser/modules/dbtext.so
#3 0x4001fd21 in dbt_db_get_table () from
/usr/local/lib/openser/modules/dbtext.so
#4 0x4001c0da in dbt_query () from
/usr/local/lib/openser/modules/dbtext.so
#5 0x080c4403 in table_version ()
#6 0x401f9edb in lcr_db_ver () from
/usr/local/lib/openser/modules/lcr.so
#7 0x401fabac in mod_init () from /usr/local/lib/openser/modules/lcr.so
#8 0x0808c556 in init_mod ()
#9 0x0808c79d in init_modules ()
#10 0x0806b831 in main ()
----------------------------------------------------------------------
Comment By: Daniel-Constantin Mierla (miconda)
Date: 2007-07-16 15:33
Message:
Logged In: YES
user_id=1246013
Originator: NO
Can you get the latest svn and try again?
Let me know any strange behavior. Do you still have all openser processes
running, any 100% CPU? Attach to MI process via gdb and print the
backtrace.
----------------------------------------------------------------------
Comment By: Ovidiu Sas (osas)
Date: 2007-07-14 15:57
Message:
Logged In: YES
user_id=1395524
Originator: YES
Yes, it is related to the new functionality of dbtext (db_mode 1).
If the db_mode is not set, 'openserctl fifo lcr_reload' returns properly
from execution:
# openserctl fifo lcr_reload
database engine 'DBTEXT' loaded
Control engine 'FIFO' loaded
entering fifo_cmd lcr_reload
FIFO command was:
:lcr_reload:openser_receiver_13343
#
Here are the debug logs:
========================
Jul 14 08:49:17 oser openser[13092]: DEBUG:mi_fifo:mi_fifo_server: entered
consume
Jul 14 08:49:17 oser openser[13092]: DEBUG:mi_fifo:mi_fifo_server: ****
done consume
Jul 14 08:49:17 oser openser[13092]: DEBUG:mi_fifo:mi_fifo_server: done
parsing the mi tree
Jul 14 08:49:17 oser openser[13092]: DBT:dbt_cache_get_db: looking for db
/usr/local/etc/openser/openser!
Jul 14 08:49:17 oser openser[13092]: DBT:dbt_cache_get_db: db already
cached!
Jul 14 08:49:17 oser openser[13092]: DBT:dbt_cache_get_table: cache or
mtime succeeded
Jul 14 08:49:17 oser openser[13092]: DBT:dbt_query: new res with 7 cols
Jul 14 08:49:17 oser openser[13092]: DBT:dbt_result_new: new res with 7
cols
Jul 14 08:49:17 oser openser[13092]: DBT:dbt_cache_get_table: cache or
mtime succeeded
Jul 14 08:49:17 oser openser[13092]: DBT:dbt_query: new res with 4 cols
Jul 14 08:49:17 oser openser[13092]: DBT:dbt_result_new: new res with 4
cols
Of course, in this mode lcr_reload is a noop (the table is already cached
in the memory).
The whole purpose of this test was to check the new dbtext enhancement
committed by miconda.
----------------------------------------------------------------------
Comment By: Ovidiu Sas (osas)
Date: 2007-07-14 15:50
Message:
Logged In: YES
user_id=1395524
Originator: YES
Update from juha:
=================
Date: 2007-07-14 05:31
Sender: nobody
Logged In: NO
i didn't find anything in lcr module that would explain lcr_reload mi
command not returning. lcr_reload calls reload_gws(), which contains two
controlled loops, i.e., they cannot loop forever.
so perhaps the bug is in dbtext implementation.
-- juha
----------------------------------------------------------------------
You can respond by visiting:
https://sourceforge.net/tracker/?func=detail&atid=743020&aid=1752902&group_id=139143
More information about the Devel
mailing list