Hi,
I'm always working on my Samba server, and put the log level to 10 like
Volker said in a previous message. But, when i read any log files i see
a troubling message :
"got a SHUTDOWN message"
Every minutes !!! Is this behavior normal ?? Following, the paste of 2
sequences (so 2 minutes of a log file) :
[2009/10/04 23:09:45, 10] lib/events.c:105(run_events)
Running timed event "smbd_idle_event_handler" 0xb9ab36a8
[2009/10/04 23:09:45, 10] lib/events.c:287(s3_event_debug)
s3_event: Destroying timer event 0xb9ab36a8
"smbd_idle_event_handler"
[2009/10/04 23:09:45, 10] smbd/process.c:663(smbd_idle_event_handler)
smbd_idle_event_handler: idle_evt(deadtime) (nil) called
[2009/10/04 23:09:45, 2] smbd/process.c:1979(deadtime_fn)
Closing idle connection
[2009/10/04 23:09:45, 10] lib/messages_local.c:237(messaging_tdb_store)
messaging_tdb_store:
[2009/10/04 23:09:45, 1] ../librpc/ndr/ndr.c:214(ndr_print_debug)
array: struct messaging_array
num_messages : 0x00000001 (1)
messages: ARRAY(1)
messages: struct messaging_rec
msg_version : 0x00000002 (2)
msg_type : MSG_SHUTDOWN (13)
dest: struct server_id
id : 0x00000cb2 (3250)
src: struct server_id
id : 0x00000cb2 (3250)
buf : DATA_BLOB length=0
[2009/10/04 23:09:45, 10] smbd/process.c:667(smbd_idle_event_handler)
smbd_idle_event_handler: idle_evt(deadtime) (nil) stopped
[2009/10/04 23:09:45, 10]
lib/messages_local.c:73(messaging_tdb_signal_handler)
messaging_tdb_signal_handler: sig[10] count[1] msgs[1]
[2009/10/04 23:09:45, 10] lib/messages_local.c:447(message_dispatch)
message_dispatch: received_messages = 1
[2009/10/04 23:09:45, 10] lib/messages_local.c:196(messaging_tdb_fetch)
messaging_tdb_fetch:
[2009/10/04 23:09:45, 1] ../librpc/ndr/ndr.c:214(ndr_print_debug)
result: struct messaging_array
num_messages : 0x00000001 (1)
messages: ARRAY(1)
messages: struct messaging_rec
msg_version : 0x00000002 (2)
msg_type : MSG_SHUTDOWN (13)
dest: struct server_id
id : 0x00000cb2 (3250)
src: struct server_id
id : 0x00000cb2 (3250)
buf : DATA_BLOB length=0
[2009/10/04 23:09:45, 3] smbd/server.c:146(msg_exit_server)
got a SHUTDOWN message
[2009/10/04 23:09:45, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2009/10/04 23:09:45, 5] auth/token_util.c:522(debug_nt_user_token)
NT user token: (NULL)
[2009/10/04 23:09:45, 5] auth/token_util.c:548(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2009/10/04 23:09:45, 5] smbd/uid.c:368(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2009/10/04 23:09:45, 3] smbd/connection.c:31(yield_connection)
Yielding connection to
[2009/10/04 23:09:45, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
Locking key B20C0000FFFFFFFF0000
[2009/10/04 23:09:45, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
Allocated locked data 0x0xb9b00ad8
[2009/10/04 23:09:45, 3] smbd/connection.c:42(yield_connection)
deleting connection record returned NT_STATUS_NOT_FOUND
[2009/10/04 23:09:45, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
Unlocking key B20C0000FFFFFFFF0000
[2009/10/04 23:09:45, 3] smbd/server.c:845(exit_server_common)
Server exit (normal exit)
[2009/10/04 23:09:45, 6] param/loadparm.c:6994(lp_file_list_changed)
lp_file_list_changed()
file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Fri
Oct 2 23:47:47 2009
[2009/10/04 23:09:45, 5] smbd/reply.c:498(reply_special)
init msg_type=0x81 msg_flags=0x0
[2009/10/04 23:10:45, 10] lib/events.c:105(run_events)
Running timed event "smbd_idle_event_handler" 0xb9ab36a8
[2009/10/04 23:10:45, 10] lib/events.c:287(s3_event_debug)
s3_event: Destroying timer event 0xb9ab36a8
"smbd_idle_event_handler"
[2009/10/04 23:10:45, 10] smbd/process.c:663(smbd_idle_event_handler)
smbd_idle_event_handler: idle_evt(deadtime) (nil) called
[2009/10/04 23:10:45, 2] smbd/process.c:1979(deadtime_fn)
Closing idle connection
[2009/10/04 23:10:45, 10] lib/messages_local.c:237(messaging_tdb_store)
messaging_tdb_store:
[2009/10/04 23:10:45, 1] ../librpc/ndr/ndr.c:214(ndr_print_debug)
array: struct messaging_array
num_messages : 0x00000001 (1)
messages: ARRAY(1)
messages: struct messaging_rec
msg_version : 0x00000002 (2)
msg_type : MSG_SHUTDOWN (13)
dest: struct server_id
id : 0x00000cb6 (3254)
src: struct server_id
id : 0x00000cb6 (3254)
buf : DATA_BLOB length=0
[2009/10/04 23:10:45, 10] smbd/process.c:667(smbd_idle_event_handler)
smbd_idle_event_handler: idle_evt(deadtime) (nil) stopped
[2009/10/04 23:10:45, 10]
lib/messages_local.c:73(messaging_tdb_signal_handler)
messaging_tdb_signal_handler: sig[10] count[1] msgs[1]
[2009/10/04 23:10:45, 10] lib/messages_local.c:447(message_dispatch)
message_dispatch: received_messages = 1
[2009/10/04 23:10:45, 10] lib/messages_local.c:196(messaging_tdb_fetch)
messaging_tdb_fetch:
[2009/10/04 23:10:45, 1] ../librpc/ndr/ndr.c:214(ndr_print_debug)
result: struct messaging_array
num_messages : 0x00000001 (1)
messages: ARRAY(1)
messages: struct messaging_rec
msg_version : 0x00000002 (2)
msg_type : MSG_SHUTDOWN (13)
dest: struct server_id
id : 0x00000cb6 (3254)
src: struct server_id
id : 0x00000cb6 (3254)
buf : DATA_BLOB length=0
[2009/10/04 23:10:45, 3] smbd/server.c:146(msg_exit_server)
got a SHUTDOWN message
[2009/10/04 23:10:45, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2009/10/04 23:10:45, 5] auth/token_util.c:522(debug_nt_user_token)
NT user token: (NULL)
[2009/10/04 23:10:45, 5] auth/token_util.c:548(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2009/10/04 23:10:45, 5] smbd/uid.c:368(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2009/10/04 23:10:45, 3] smbd/connection.c:31(yield_connection)
Yielding connection to
[2009/10/04 23:10:45, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
Locking key B60C0000FFFFFFFF0000
[2009/10/04 23:10:45, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
Allocated locked data 0x0xb9b00ad8
[2009/10/04 23:10:45, 3] smbd/connection.c:42(yield_connection)
deleting connection record returned NT_STATUS_NOT_FOUND
[2009/10/04 23:10:45, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
Unlocking key B60C0000FFFFFFFF0000
[2009/10/04 23:10:45, 3] smbd/server.c:845(exit_server_common)
[2009/10/04 23:10:45, 6] param/loadparm.c:6994(lp_file_list_changed)
lp_file_list_changed()
file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Fri
Oct 2 23:47:47 2009
[2009/10/04 23:10:45, 5] smbd/reply.c:498(reply_special)
init msg_type=0x81 msg_flags=0x0
I've got 722 "got a SHUTDOWN" between 04-Oct-2009 at 21:43:28 and
Today
(05-Oct-2009) at 9:50:25... This server hasn't been used during this period.
I don't know if there is a relation between this behavior and my
problems (see smbd crash messages), but this servers have restarted this
night (04-Oct at 23:00) at 00:00 his load average was 0.00 and Mem used
: 190 Mb. This morning (9:50) his load average is 0.00 but Mem Used have
growed to 2.37 Gb (without any activities).
To be continued...
Regards,
Bruno
--
Bruno MACADRE
-------------------------------------------------------------------
Ing?nieur Syst?mes et R?seau | Systems and Network Engineer
D?partement Informatique | Department of computer science
Responsable R?seau et T?l?phonie | Telecom and Network Manager
Universit? de Rouen | University of Rouen
-------------------------------------------------------------------
Coordonn?es / Contact :
Universit? de Rouen
Facult? des Sciences et Techniques - Madrillet
Avenue de l'Universit? - BP12
76801 St Etienne du Rouvray CEDEX
T?l : +33 (0)2-32-95-51-86
Fax : +33 (0)2-32-95-51-87
-------------------------------------------------------------------