Ebbe Hansen
2002-Aug-11 15:16 UTC
[Samba] Locking problem reported when starting smbd on a second systen!
I was successful installing and configured Samba 2.2.5 on my first Solaris machine. However, the second Solaris machine returns errno=11 (EAGAIN ~ Service Temporary Unavailable). I have tried to delete the "locks" directory to remove possible "old" locks - upon startup the smbd create new log files, but the same errors are reported again. Why only on one of my systems - I have checked all directory privileges and tried to work with almost identical smb.conf files? I have verified that both systems run with identical kernel-tuning parameters! Same problem occurs even when the first Solaris server is not running - I suspect something must be different on the two Solaris systems - just can't find-out what! I even tried to compile Samba on each system! This is the error I see reported 7 times when starting (see dump below). 2002/08/11 16:35:42, 5] tdb/tdbutil.c:(492) tdb(unknown): tdb_brlock failed (fd=14) at offset 4 rw_type=2 lck_type=34 errno=11 (Note I changed the Samba 2.2.5 source code to dump the "errno" value). Regards Ebbe Hansen ----------------- smbd log file upon startup - failing ock-calls ------------------------- [2002/08/11 16:35:39, 6] param/loadparm.c:(2285) lp_file_list_changed() file /usr/local/samba/lib/smb.conf -> /usr/local/samba/lib/smb.conf last mod_ time: Sun Aug 11 16:33:31 2002 [2002/08/11 16:35:39, 2] lib/interface.c:(81) added interface ip=10.101.12.217 bcast=10.101.255.255 nmask=255.255.0.0 [2002/08/11 16:35:39, 5] lib/hash.c:(68) Hash size = 521. [2002/08/11 16:35:39, 6] lib/charset.c:(336) codepage_initialise: client code page = 850 [2002/08/11 16:35:39, 5] lib/charset.c:(194) load_client_codepage: loading codepage 850. Adding chars 0x85 0xb7 (l->u = True) (u->l = True) Adding chars 0xa0 0xb5 (l->u = True) (u->l = True) Adding chars 0x83 0xb6 (l->u = True) (u->l = True) Adding chars 0xc6 0xc7 (l->u = True) (u->l = True) Adding chars 0x84 0x8e (l->u = True) (u->l = True) Adding chars 0x86 0x8f (l->u = True) (u->l = True) Adding chars 0x91 0x92 (l->u = True) (u->l = True) Adding chars 0x87 0x80 (l->u = True) (u->l = True) Adding chars 0x8a 0xd4 (l->u = True) (u->l = True) Adding chars 0x82 0x90 (l->u = True) (u->l = True) Adding chars 0x88 0xd2 (l->u = True) (u->l = True) Adding chars 0x89 0xd3 (l->u = True) (u->l = True) Adding chars 0x8d 0xde (l->u = True) (u->l = True) Adding chars 0xa1 0xd6 (l->u = True) (u->l = True) Adding chars 0x8c 0xd7 (l->u = True) (u->l = True) Adding chars 0x8b 0xd8 (l->u = True) (u->l = True) Adding chars 0xd0 0xd1 (l->u = True) (u->l = True) Adding chars 0xa4 0xa5 (l->u = True) (u->l = True) Adding chars 0x95 0xe3 (l->u = True) (u->l = True) Adding chars 0xa2 0xe0 (l->u = True) (u->l = True) Adding chars 0x93 0xe2 (l->u = True) (u->l = True) Adding chars 0xe4 0xe5 (l->u = True) (u->l = True) Adding chars 0x94 0x99 (l->u = True) (u->l = True) Adding chars 0x9b 0x9d (l->u = True) (u->l = True) Adding chars 0x97 0xeb (l->u = True) (u->l = True) Adding chars 0xa3 0xe9 (l->u = True) (u->l = True) Adding chars 0x96 0xea (l->u = True) (u->l = True) Adding chars 0x81 0x9a (l->u = True) (u->l = True) Adding chars 0xec 0xed (l->u = True) (u->l = True) Adding chars 0xe7 0xe8 (l->u = True) (u->l = True) Adding chars 0x9c 0x0 (l->u = False) (u->l = False) [2002/08/11 16:35:39, 5] lib/util_unistr.c:(597) load_unicode_map: loading unicode map for codepage 850. [2002/08/11 16:35:39, 5] lib/util_unistr.c:(597) load_unicode_map: loading unicode map for codepage ISO8859-1. [2002/08/11 16:35:39, 3] smbd/server.c:(747) loaded services [2002/08/11 16:35:39, 3] smbd/server.c:(762) Becoming a daemon. [2002/08/11 16:35:39, 8] lib/util.c:(1302) fcntl_lock 7 34 0 1 2 [2002/08/11 16:35:39, 8] lib/util.c:(1340) fcntl_lock: Lock call successful [2002/08/11 16:35:39, 5] smbd/connection.c:(156) claiming 0 [2002/08/11 16:35:39, 3] lib/util_sock.c:(813) bind succeeded on port 139 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option SO_KEEPALIVE = 8 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option SO_REUSEADDR = 4 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option SO_BROADCAST = 0 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option TCP_NODELAY = 0 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option IPTOS_LOWDELAY = 0 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option IPTOS_THROUGHPUT = 0 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option SO_SNDBUF = 16384 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option SO_RCVBUF = 24576 [2002/08/11 16:35:39, 5] lib/util_sock.c:(109) Could not test socket option SO_SNDLOWAT. [2002/08/11 16:35:39, 5] lib/util_sock.c:(109) Could not test socket option SO_RCVLOWAT. [2002/08/11 16:35:39, 5] lib/util_sock.c:(109) Could not test socket option SO_SNDTIMEO. [2002/08/11 16:35:39, 5] lib/util_sock.c:(109) Could not test socket option SO_RCVTIMEO. [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option SO_KEEPALIVE = 8 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option SO_REUSEADDR = 4 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option SO_BROADCAST = 0 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option TCP_NODELAY = 1 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option IPTOS_LOWDELAY = 0 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option IPTOS_THROUGHPUT = 0 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option SO_SNDBUF = 16384 [2002/08/11 16:35:39, 5] lib/util_sock.c:(111) socket option SO_RCVBUF = 24576 [2002/08/11 16:35:39, 5] lib/util_sock.c:(109) Could not test socket option SO_SNDLOWAT. [2002/08/11 16:35:39, 5] lib/util_sock.c:(109) Could not test socket option SO_RCVLOWAT. [2002/08/11 16:35:39, 5] lib/util_sock.c:(109) Could not test socket option SO_SNDTIMEO. [2002/08/11 16:35:39, 5] lib/util_sock.c:(109) Could not test socket option SO_RCVTIMEO. [2002/08/11 16:35:39, 2] smbd/server.c:(215) waiting for a connection [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_KEEPALIVE = 8 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_REUSEADDR = 4 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_BROADCAST = 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option TCP_NODELAY = 1 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option IPTOS_LOWDELAY = 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option IPTOS_THROUGHPUT = 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_SNDBUF = 32768 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_RCVBUF = 32768 [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_SNDLOWAT. [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_RCVLOWAT. [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_SNDTIMEO. [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_RCVTIMEO. [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_KEEPALIVE = 8 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_REUSEADDR = 4 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_BROADCAST = 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option TCP_NODELAY = 1 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option IPTOS_LOWDELAY = 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option IPTOS_THROUGHPUT = 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_SNDBUF = 32768 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_RCVBUF = 32768 [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_SNDLOWAT. [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_RCVLOWAT. [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_SNDTIMEO. [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_RCVTIMEO. [2002/08/11 16:35:42, 6] param/loadparm.c:(2285) lp_file_list_changed() file /usr/local/samba/lib/smb.conf -> /usr/local/samba/lib/smb.conf last mod_ time: Sun Aug 11 16:33:31 2002 [2002/08/11 16:35:42, 3] smbd/oplock.c:(1211) open_oplock_ipc: opening loopback UDP socket. [2002/08/11 16:35:42, 3] lib/util_sock.c:(813) bind succeeded on port 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_KEEPALIVE = 8 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_REUSEADDR = 4 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_BROADCAST = 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option TCP_NODELAY = 1 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option IPTOS_LOWDELAY = 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option IPTOS_THROUGHPUT = 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_SNDBUF = 32768 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_RCVBUF = 32768 [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_SNDLOWAT. [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_RCVLOWAT. [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_SNDTIMEO. [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_RCVTIMEO. [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_KEEPALIVE = 8 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_REUSEADDR = 4 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_BROADCAST = 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option TCP_NODELAY = 1 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option IPTOS_LOWDELAY = 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option IPTOS_THROUGHPUT = 0 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_SNDBUF = 32768 [2002/08/11 16:35:42, 5] lib/util_sock.c:(111) socket option SO_RCVBUF = 32768 [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_SNDLOWAT. [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_RCVLOWAT. [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_SNDTIMEO. [2002/08/11 16:35:42, 5] lib/util_sock.c:(109) Could not test socket option SO_RCVTIMEO. [2002/08/11 16:35:42, 5] tdb/tdbutil.c:(492) tdb(unknown): tdb_brlock failed (fd=10) at offset 4 rw_type=2 lck_type=34 errno=11 [2002/08/11 16:35:42, 5] tdb/tdbutil.c:(492) tdb(unknown): tdb_brlock failed (fd=14) at offset 4 rw_type=2 lck_type=34 errno=11 [2002/08/11 16:35:42, 5] tdb/tdbutil.c:(492) tdb(unknown): tdb_brlock failed (fd=15) at offset 4 rw_type=2 lck_type=34 errno=11 [2002/08/11 16:35:42, 5] tdb/tdbutil.c:(492) tdb(unknown): tdb_brlock failed (fd=16) at offset 4 rw_type=2 lck_type=34 errno=11 [2002/08/11 16:35:42, 5] tdb/tdbutil.c:(492) tdb(unknown): tdb_brlock failed (fd=17) at offset 4 rw_type=2 lck_type=34 errno=11 [2002/08/11 16:35:42, 5] tdb/tdbutil.c:(492) tdb(unknown): tdb_brlock failed (fd=18) at offset 4 rw_type=2 lck_type=34 errno=11 [2002/08/11 16:35:42, 5] tdb/tdbutil.c:(492) tdb(unknown): tdb_brlock failed (fd=19) at offset 4 rw_type=2 lck_type=34 errno=11 [2002/08/11 16:35:42, 6] param/loadparm.c:(2285) lp_file_list_changed() file /usr/local/samba/lib/smb.conf -> /usr/local/samba/lib/smb.conf last mod_ time: Sun Aug 11 16:33:31 2002 [2002/08/11 16:35:42, 3] smbd/oplock.c:(1211) open_oplock_ipc: opening loopback UDP socket. [2002/08/11 16:35:42, 3] lib/util_sock.c:(813) bind succeeded on port 0 [2002/08/11 16:35:42, 3] smbd/oplock.c:(1242) open_oplock ipc: pid = 2462, global_oplock_port = 32896 [2002/08/11 16:35:42, 4] lib/time.c:(114) Serverzone is 25200 [2002/08/11 16:35:42, 3] smbd/process.c:(1102) end of file from client [2002/08/11 16:35:42, 3] smbd/sec_ctx.c:(314) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2002/08/11 16:35:42, 5] smbd/uid.c:(217) change_to_root_user: now uid=(0,0) gid=(0,0) [2002/08/11 16:35:42, 2] smbd/server.c:(461) Closing connections [2002/08/11 16:35:42, 3] smbd/connection.c:(48) Yielding connection to [2002/08/11 16:35:42, 3] smbd/connection.c:(62) yield_connection: tdb_delete for name failed with error Record does not exist . [2002/08/11 16:35:42, 5] smbd/oplock.c:(108) receive_local_message: doing select with timeout of 1 ms [2002/08/11 16:35:42, 3] smbd/server.c:(495) Server exit (normal exit) [2002/08/11 16:35:42, 3] smbd/oplock.c:(1242) open_oplock ipc: pid = 2463, global_oplock_port = 32897 [2002/08/11 16:35:42, 4] lib/time.c:(114) Serverzone is 25200 [2002/08/11 16:35:42, 3] smbd/process.c:(1102) end of file from client [2002/08/11 16:35:42, 3] smbd/sec_ctx.c:(314) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2002/08/11 16:35:42, 5] smbd/uid.c:(217) change_to_root_user: now uid=(0,0) gid=(0,0) [2002/08/11 16:35:42, 2] smbd/server.c:(461) Closing connections [2002/08/11 16:35:42, 3] smbd/connection.c:(48) Yielding connection to [2002/08/11 16:35:42, 3] smbd/connection.c:(62) yield_connection: tdb_delete for name failed with error Record does not exist . [2002/08/11 16:35:42, 5] smbd/oplock.c:(108) receive_local_message: doing select with timeout of 1 ms [2002/08/11 16:35:42, 3] smbd/server.c:(495) Server exit (normal exit)