Hi there !
I just upgraded (last friday night) our main Samba host to Samba 2.0.0 from
version 1.9.18p10. This host provides access to our own file based database
system. The number of Samba clients is around 60 (nearly all NT4.0's and
few NT3.51's). The Samba 2.0.0 host runs
'uname -a':
HP-UX vertex A.09.05 A 9000/720 65914162 two-user license
After the upgrade change I've been having this ERROR in smb.log (normal log
level was 1):
..
'check_access_allowed_for_current_user: The process is no longer
waiting!'
..
this whole week few times a day. And this gives major problems to our
database users since the file being opened is one of most used database
file (d_PROREG) and this gives other problems if it can't be opened.
What's
funny is that most of these error symptoms seem to come from one particular
NT4 host (piai_nt) or maybe it is just that it uses the databases most ?!
First it seemed that the problems occurred if the user run 2 of the same
program (everything through Samba!) simultaneously but today this happened
with just one program.
Anyhow I changed Samba to use special log file for this one host (by
creating smb.conf.piai_nt) and by raising its log level to 3 by giving few
'kill -USR1 <pid>'. The size of the log file grows so rapidly that
I could
just use level 3.
var/smb.log.piai_nt (log level 3):
(You can see that the file 'd_LASKUTUS' is first used successfully then
somehow failed to open it and afterwards once again successfully opened ?!)
..
[1999/01/21 14:02:33, 3] smbd/process.c:(402)
switch message SMBreadX
(pid 26589)
[1999/01/21 14:02:33, 3] smbd/reply.c:(2196)
readX fnum=5054
min=8192 max=8192 nread=5385
[1999/01/21 14:02:33, 3] smbd/process.c:(565)
Transaction 202300 of length 46
[1999/01/21 14:02:33, 3]
smbd/process.c:(402)
switch message SMBclose (pid 26589)
[1999/01/21
14:02:33, 3] smbd/reply.c:(2637)
close fd=15 fnum=5054
(numopen=11)
[1999/01/21 14:02:33, 2] locking/locking_shm.c:(353)
del_share_modes Deleting share mode entry dev=7201600 ino=47318
[1999/01/21
14:02:33, 2] locking/locking_shm.c:(374)
del_share_modes num entries = 0,
deleting share_mode dev=7201600 ino=47318
[1999/01/21 14:02:33, 3]
smbd/open.c:(155)
fd_attempt_close fd = 15, dev = 7201600, inode = 47318,
open_flags = 2, ref_count = 1.
[1999/01/21 14:02:33, 2] smbd/close.c:(163)
vertex closed file PROJEKTIT/dbases/d_LASKUTUSc (numopen=10)
[1999/01/21
14:02:33, 3] smbd/process.c:(565)
Transaction 202301 of length
574
[1999/01/21 14:02:33, 3] smbd/process.c:(402)
switch message SMBwrite
(pid 26589)
[1999/01/21 14:02:33, 3] smbd/reply.c:(2411)
write fnum=5053
num=522 wrote=522
[1999/01/21 14:02:33, 3] smbd/process.c:(565)
Transaction 202302 of length 46
[1999/01/21 14:02:33, 3]
smbd/process.c:(402)
switch message SMBclose (pid 26589)
[1999/01/21
14:02:33, 3] smbd/reply.c:(2637)
close fd=12 fnum=5053
(numopen=10)
[1999/01/21 14:02:33, 2] locking/locking_shm.c:(353)
del_share_modes Deleting share mode entry dev=7201600 ino=47265
[1999/01/21
14:02:33, 3] smbd/open.c:(155)
fd_attempt_close fd = 12, dev = 7201600,
inode = 47265, open_flags = 2, ref_count = 2.
[1999/01/21 14:02:33, 2]
smbd/close.c:(163)
vertex closed file PROJEKTIT/dbases/d_LASKUTUS
(numopen=9)
[1999/01/21 14:02:33, 3] smbd/process.c:(565)
Transaction
202303 of length 116
[1999/01/21 14:02:33, 3] smbd/process.c:(402)
switch
message SMBntcreateX (pid 26589)
[1999/01/21 14:02:33, 3] lib/util.c:(609)
unix_clean_name [/PROJEKTIT/dbases/d_LASKUTUS]
[1999/01/21 14:02:33, 3]
lib/util.c:(609)
unix_clean_name
[PROJEKTIT/dbases/d_LASKUTUS]
[1999/01/21 14:02:33, 3] smbd/files.c:(130)
Re-used file_fd_struct dev = 7201600, inode = 47265, ref_count 2
[1999/01/21 14:02:33, 0] smbd/open.c:(229)
check_access_allowed_for_current_user: The process is no longer
waiting!
[1999/01/21 14:02:33, 3] smbd/open.c:(403)
Permission denied
opening file PROJEKTIT/dbases/d_LASKUTUS (flags=2, accmode = 2)
[1999/01/21
14:02:33, 3] smbd/error.c:(133)
error packet at line 785 cmd=162
(SMBntcreateX) eclass=1 ecode=5
[1999/01/21 14:02:33, 3]
smbd/error.c:(143)
error string = Permission denied
[1999/01/21 14:02:33,
3] smbd/process.c:(565)
Transaction 202304 of length 75
[1999/01/21
14:02:33, 3] smbd/process.c:(402)
switch message SMBlockingX (pid
26589)
[1999/01/21 14:02:33, 3] smbd/reply.c:(4055)
lockingX fnum=4993
type=16 num_locks=1 num_ulocks=0
[1999/01/21 14:02:33, 3]
smbd/process.c:(565)
Transaction 202305 of length 75
[1999/01/21
14:02:33, 3] smbd/process.c:(402)
switch message SMBlockingX (pid
26589)
[1999/01/21 14:02:33, 3] smbd/reply.c:(4055)
lockingX fnum=4993
type=16 num_locks=0 num_ulocks=1
[1999/01/21 14:02:34, 3]
smbd/process.c:(565)
Transaction 202306 of length 75
[1999/01/21
14:02:34, 3] smbd/process.c:(402)
switch message SMBlockingX (pid
26589)
[1999/01/21 14:02:34, 3] smbd/reply.c:(4055)
lockingX fnum=4993
type=16 num_locks=1 num_ulocks=0
[1999/01/21 14:02:34, 3]
smbd/process.c:(565)
Transaction 202307 of length 75
[1999/01/21
14:02:34, 3] smbd/process.c:(402)
switch message SMBlockingX (pid
26589)
[1999/01/21 14:02:34, 3] smbd/reply.c:(4055)
lockingX fnum=4993
type=16 num_locks=0 num_ulocks=1
[1999/01/21 14:02:34, 3]
smbd/process.c:(565)
Transaction 202308 of length 116
[1999/01/21
14:02:34, 3] smbd/process.c:(402)
switch message SMBntcreateX (pid
26589)
[1999/01/21 14:02:34, 3] lib/util.c:(609)
unix_clean_name
[/PROJEKTIT/dbases/d_LASKUTUS]
[1999/01/21 14:02:34, 3] lib/util.c:(609)
unix_clean_name [PROJEKTIT/dbases/d_LASKUTUS]
[1999/01/21 14:02:34, 3]
smbd/files.c:(130)
Re-used file_fd_struct dev = 7201600, inode = 47265,
ref_count = 2
[1999/01/21 14:02:34, 2] smbd/open.c:(566)
pia opened file
PROJEKTIT/dbases/d_LASKUTUS read=Yes write=Yes (numopen=10)
[1999/01/21
14:02:34, 3] locking/locking_shm.c:(493)
set_share_mode: Created share
entry for PROJEKTIT/dbases/d_LASKUTUS with mode 0x42 pid=26589
[1999/01/21
14:02:34, 3] smbd/process.c:(565)
Transaction 202309 of length
76
[1999/01/21 14:02:34, 3] smbd/process.c:(402)
switch message SMBtrans2
(pid 26589)
[1999/01/21 14:02:34, 3] smbd/trans2.c:(1254)
call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 258
[1999/01/21
14:02:34, 3] smbd/trans2.c:(1311)
call_trans2qfilepathinfo
PROJEKTIT/dbases/d_LASKUTUS level=258 call=7 total_data=0
[1999/01/21
14:02:34, 3] smbd/process.c:(565)
Transaction 202310 of length
108
[1999/01/21 14:02:34, 3] smbd/process.c:(402)
switch message
SMBtrans2 (pid 26589)
[1999/01/21 14:02:34, 3] smbd/trans2.c:(1294)
call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 257
[1999/01/21
14:02:34, 3] lib/util.c:(609)
unix_clean_name
[/PROJEKTIT/dbases/d_LASKUTUSc]
..
smb.conf (the major differences):
..
oplocks = false
locking = yes
share modes = yes
security = share
..
Samba 2.0.0 was compiled by me with HP's C compiler. I just ran
'configure'
and then 'make':
configure got e.g. these in config.h:
..
#define HAVE_SIGACTION 1
#define HAVE_SIGBLOCK 1
#define HAVE_SIGPROCMASK
1
..
I checked the source code 'open.c' and the function comments on
check_access_allowed_for_current_user() and it says that there is something
ugly in it with POSIX locking braindamage!
About one year ago (Samba Digest, Jan 1998: HPUX compilations and Samba
1.9.18p2)
I had problems with HPUX when the flag #define SIGCLD_IGNORE was removed
from HPUX. Lots of <defunct> processes appeared. Maybe this one relates to
this and the signal handling in general in HPUX 9.0x compared to other Unixes?
Does anyone have this kind of problems in HPUX 10.20? I know that we have
to upgrade to at least this version some day along this year to overcome
future Y2K problems, so hopefully this situation won't happen!
I decided to go back to Samba version 1.9.18p10 for the time being :-(
------
Panu Outinen Tel. +358 3 318 2500
Vertex Systems Oy Fax +358 3 318 2450
Vaajakatu 9 http://www.vertex.fi
33720 Tampere, FINLAND email: Panu.Outinen@vertex.fi