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