Denis Sbragion
2001-Oct-18 02:18 UTC
File not found/No permissions problem (2.2.2, RH7.1,Kernel 2.4.2)
Hello,
I have a weird problem happening sometimes with samba 2.2.2 and 2.2.1 on
RedHat 7.1 with a 2.4.2 kernel. I'm using the binary distributions from
samba.org. Every now and then I get a "File not found" or "No
permission"
even if the file is obviously present and permissions are ok. This seem to
happen only with Win98, not NT o 2K. Sometimes it happens with files that
worked perfectly just the day before, sometimes a file fails on a machine
and at the same time works on another, sometimes just copying the file on
another share make it works, sometimes you can't open the file with one
application (Notepad) but you can with another (WordPad), even on the same
machine and on the same share.
This happens only opening files from applications (happened with
Access, Visual Basic, Notepad), from Explorer you can always copy, rename
delete or do whatever you want, so I'm pretty sure it' isn't a
permission
problem. I tried playing with different settings (mangling, oplocks and
kernel oplocks, forcing users/groups to different values and so on) but got
no difference. This didn't happened with samba 2.2.1 (compiled from source)
with a similar configuration on an old Slackware (3.2) with a 2.0.38
kernel, so may be it is kernel related, but i'm not sure. I'm using
samba
since 1.5 and I have good experience with it so I'm pretty sure
configuration is ok.
I tested using a level 10 debug log, but the file that gives the
error today (ITMonitor.vbp) does'nt even appear in the log (!). I happend
the log just before the failure (it isn't so long), hoping it could help.
The file has the right permissions:
-rw-r--r-- 1 root root 1931 Oct 17 17:29 ITMonitor.vbp
The share involved as force user = root and force group = root. The
application (Visual Basic opening a project group 'ITMonitor.vbg' with
the
project ITMonitor.vbp in it) tries to open the file using the UNC, but with
other application the problems happens also using a network disk.
Thanks in advance for any help.
Bye.
------------
Oct 18 10:09:46 server smbd[1630]: [2001/10/18 10:09:46, 3]
smbd/vfs.c:vfs_ChDir(658)
Oct 18 10:09:46 server smbd[1630]: vfs_ChDir to /disks/scsi1/samba/Projects
Oct 18 10:09:46 server smbd[1630]: [2001/10/18 10:09:46, 3]
smbd/trans2.c:call_trans2findfirst(671)
Oct 18 10:09:46 server smbd[1630]: call_trans2findfirst: dirtype = 22,
maxentries = 6, close_after_first=0, close_if_end = 0 requires_resume_key =
0 level = 260, max_data_bytes = 2432
Oct 18 10:09:46 server smbd[1630]: [2001/10/18 10:09:46, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:46 server smbd[1630]: unix_clean_name
[/ITMONITOR/SORGENTIDLL/ITMONITOR.VBG]
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:47 server smbd[1630]: unix_clean_name
[ITMonitor/SorgentiDLL/ITMonitor.vbg]
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:47 server smbd[1630]: unix_clean_name [ITMonitor/SorgentiDLL]
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/dir.c:dptr_create(490)
Oct 18 10:09:47 server smbd[1630]: creating new dirptr 256 for path
ITMonitor/SorgentiDLL, expect_close = 1
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:process_smb(860)
Oct 18 10:09:47 server smbd[1630]: Transaction 524 of length 41
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:switch_message(667)
Oct 18 10:09:47 server smbd[1630]: switch message SMBfindclose (pid 1630)
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(320)
Oct 18 10:09:47 server smbd[1630]: setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(326)
Oct 18 10:09:47 server smbd[1630]: 7 user groups:
Oct 18 10:09:47 server smbd[1630]: 0 1 2 3 4 6 10
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/trans2.c:reply_findclose(2387)
Oct 18 10:09:47 server smbd[1630]: reply_findclose, dptr_num = 256
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/trans2.c:reply_findclose(2393)
Oct 18 10:09:47 server smbd[1630]: SMBfindclose dptr_num = -3
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:process_smb(860)
Oct 18 10:09:47 server smbd[1630]: Transaction 525 of length 63
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:switch_message(667)
Oct 18 10:09:47 server smbd[1630]: switch message SMBgetatr (pid 1630)
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(320)
Oct 18 10:09:47 server smbd[1630]: setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(326)
Oct 18 10:09:47 server smbd[1630]: 7 user groups:
Oct 18 10:09:47 server smbd[1630]: 0 1 2 3 4 6 10
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:47 server smbd[1630]: unix_clean_name [/ITMONITOR/SORGENTIDLL]
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:47 server smbd[1630]: unix_clean_name [ITMonitor/SorgentiDLL]
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/reply.c:reply_getatr(1231)
Oct 18 10:09:47 server smbd[1630]: getatr name=ITMonitor/SorgentiDLL
mode=16 size=0
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:process_smb(860)
Oct 18 10:09:47 server smbd[1630]: Transaction 526 of length 77
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:switch_message(667)
Oct 18 10:09:47 server smbd[1630]: switch message SMBgetatr (pid 1630)
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(320)
Oct 18 10:09:47 server smbd[1630]: setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(326)
Oct 18 10:09:47 server smbd[1630]: 7 user groups:
Oct 18 10:09:47 server smbd[1630]: 0 1 2 3 4 6 10
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:47 server smbd[1630]: unix_clean_name
[/ITMONITOR/SORGENTIDLL/ITMONITOR.VBG]
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:47 server smbd[1630]: unix_clean_name
[ITMonitor/SorgentiDLL/ITMonitor.vbg]
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/reply.c:reply_getatr(1231)
Oct 18 10:09:47 server smbd[1630]: getatr
name=ITMonitor/SorgentiDLL/ITMonitor.vbg mode=0 size=119
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:process_smb(860)
Oct 18 10:09:47 server smbd[1630]: Transaction 527 of length 63
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:switch_message(667)
Oct 18 10:09:47 server smbd[1630]: switch message SMBgetatr (pid 1630)
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(320)
Oct 18 10:09:47 server smbd[1630]: setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(326)
Oct 18 10:09:47 server smbd[1630]: 7 user groups:
Oct 18 10:09:47 server smbd[1630]: 0 1 2 3 4 6 10
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:47 server smbd[1630]: unix_clean_name [/ITMONITOR/SORGENTIDLL]
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:47 server smbd[1630]: unix_clean_name [ITMonitor/SorgentiDLL]
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/reply.c:reply_getatr(1231)
Oct 18 10:09:47 server smbd[1630]: getatr name=ITMonitor/SorgentiDLL
mode=16 size=0
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:process_smb(860)
Oct 18 10:09:47 server smbd[1630]: Transaction 528 of length 41
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:switch_message(667)
Oct 18 10:09:47 server smbd[1630]: switch message SMBgetatr (pid 1630)
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(320)
Oct 18 10:09:47 server smbd[1630]: setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(326)
Oct 18 10:09:47 server smbd[1630]: 7 user groups:
Oct 18 10:09:47 server smbd[1630]: 0 1 2 3 4 6 10
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/reply.c:reply_getatr(1231)
Oct 18 10:09:47 server smbd[1630]: getatr name= mode=18 size=0
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:process_smb(860)
Oct 18 10:09:47 server smbd[1630]: Transaction 529 of length 93
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:switch_message(667)
Oct 18 10:09:47 server smbd[1630]: switch message SMBtrans2 (pid 1630)
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(320)
Oct 18 10:09:47 server smbd[1630]: setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(326)
Oct 18 10:09:47 server smbd[1630]: 7 user groups:
Oct 18 10:09:47 server smbd[1630]: 0 1 2 3 4 6 10
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/trans2.c:call_trans2findfirst(671)
Oct 18 10:09:47 server smbd[1630]: call_trans2findfirst: dirtype = 22,
maxentries = 6, close_after_first=0, close_if_end = 0 requires_resume_key =
0 level = 260, max_data_bytes = 2432
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:47 server smbd[1630]: unix_clean_name [/ITMONITOR]
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:47 server smbd[1630]: unix_clean_name [ITMonitor]
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:47 server smbd[1630]: unix_clean_name [./]
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/dir.c:dptr_create(490)
Oct 18 10:09:47 server smbd[1630]: creating new dirptr 256 for path ./,
expect_close = 1
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:process_smb(860)
Oct 18 10:09:47 server smbd[1630]: Transaction 530 of length 41
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/process.c:switch_message(667)
Oct 18 10:09:47 server smbd[1630]: switch message SMBfindclose (pid 1630)
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(320)
Oct 18 10:09:47 server smbd[1630]: setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/sec_ctx.c:set_sec_ctx(326)
Oct 18 10:09:47 server smbd[1630]: 7 user groups:
Oct 18 10:09:47 server smbd[1630]: 0 1 2 3 4 6 10
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/trans2.c:reply_findclose(2387)
Oct 18 10:09:47 server smbd[1630]: reply_findclose, dptr_num = 256
Oct 18 10:09:47 server smbd[1630]: [2001/10/18 10:09:47, 3]
smbd/trans2.c:reply_findclose(2393)
Oct 18 10:09:47 server smbd[1630]: SMBfindclose dptr_num = -3
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/process.c:process_smb(860)
Oct 18 10:09:48 server smbd[1630]: Transaction 531 of length 105
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/process.c:switch_message(667)
Oct 18 10:09:48 server smbd[1630]: switch message SMBtrans2 (pid 1630)
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/sec_ctx.c:set_sec_ctx(320)
Oct 18 10:09:48 server smbd[1630]: setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/sec_ctx.c:set_sec_ctx(326)
Oct 18 10:09:48 server smbd[1630]: 7 user groups:
Oct 18 10:09:48 server smbd[1630]: 0 1 2 3 4 6 10
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/trans2.c:call_trans2findfirst(671)
Oct 18 10:09:48 server smbd[1630]: call_trans2findfirst: dirtype = 22,
maxentries = 6, close_after_first=0, close_if_end = 0 requires_resume_key =
0 level = 260, max_data_bytes = 2432
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:48 server smbd[1630]: unix_clean_name [/ITMONITOR/SORGENTIDLL]
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:48 server smbd[1630]: unix_clean_name [ITMonitor/SorgentiDLL]
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:48 server smbd[1630]: unix_clean_name [ITMonitor]
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/dir.c:dptr_create(490)
Oct 18 10:09:48 server smbd[1630]: creating new dirptr 256 for path
ITMonitor, expect_close = 1
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/process.c:process_smb(860)
Oct 18 10:09:48 server smbd[1630]: Transaction 532 of length 41
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/process.c:switch_message(667)
Oct 18 10:09:48 server smbd[1630]: switch message SMBfindclose (pid 1630)
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/sec_ctx.c:set_sec_ctx(320)
Oct 18 10:09:48 server smbd[1630]: setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/sec_ctx.c:set_sec_ctx(326)
Oct 18 10:09:48 server smbd[1630]: 7 user groups:
Oct 18 10:09:48 server smbd[1630]: 0 1 2 3 4 6 10
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/trans2.c:reply_findclose(2387)
Oct 18 10:09:48 server smbd[1630]: reply_findclose, dptr_num = 256
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/trans2.c:reply_findclose(2393)
Oct 18 10:09:48 server smbd[1630]: SMBfindclose dptr_num = -3
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/process.c:process_smb(860)
Oct 18 10:09:48 server smbd[1630]: Transaction 533 of length 119
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/process.c:switch_message(667)
Oct 18 10:09:48 server smbd[1630]: switch message SMBtrans2 (pid 1630)
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/sec_ctx.c:set_sec_ctx(320)
Oct 18 10:09:48 server smbd[1630]: setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/sec_ctx.c:set_sec_ctx(326)
Oct 18 10:09:48 server smbd[1630]: 7 user groups:
Oct 18 10:09:48 server smbd[1630]: 0 1 2 3 4 6 10
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/trans2.c:call_trans2findfirst(671)
Oct 18 10:09:48 server smbd[1630]: call_trans2findfirst: dirtype = 22,
maxentries = 6, close_after_first=0, close_if_end = 0 requires_resume_key =
0 level = 260, max_data_bytes = 2432
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:48 server smbd[1630]: unix_clean_name
[/ITMONITOR/SORGENTIDLL/ITMONITOR.VBG]
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:48 server smbd[1630]: unix_clean_name
[ITMonitor/SorgentiDLL/ITMonitor.vbg]
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
lib/util.c:unix_clean_name(391)
Oct 18 10:09:48 server smbd[1630]: unix_clean_name [ITMonitor/SorgentiDLL]
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/dir.c:dptr_create(490)
Oct 18 10:09:48 server smbd[1630]: creating new dirptr 256 for path
ITMonitor/SorgentiDLL, expect_close = 1
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/process.c:process_smb(860)
Oct 18 10:09:48 server smbd[1630]: Transaction 534 of length 41
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/process.c:switch_message(667)
Oct 18 10:09:48 server smbd[1630]: switch message SMBfindclose (pid 1630)
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/sec_ctx.c:set_sec_ctx(320)
Oct 18 10:09:48 server smbd[1630]: setting sec ctx (0, 0) -
sec_ctx_stack_ndx = 0
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/sec_ctx.c:set_sec_ctx(326)
Oct 18 10:09:48 server smbd[1630]: 7 user groups:
Oct 18 10:09:48 server smbd[1630]: 0 1 2 3 4 6 10
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/trans2.c:reply_findclose(2387)
Oct 18 10:09:48 server smbd[1630]: reply_findclose, dptr_num = 256
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/trans2.c:reply_findclose(2393)
Oct 18 10:09:48 server smbd[1630]: SMBfindclose dptr_num = -3
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/process.c:process_smb(860)
Oct 18 10:09:48 server smbd[1630]: Transaction 535 of length 41
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/process.c:switch_message(667)
Oct 18 10:09:48 server smbd[1630]: switch message SMBgetatr (pid 1630)
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/sec_ctx.c:set_sec_ctx(320)
Oct 18 10:09:48 server smbd[1630]: setting sec ctx (500, 101) -
sec_ctx_stack_ndx = 0
Oct 18 10:09:48 server smbd[1630]: [2001/10/18 10:09:48, 3]
smbd/sec_ctx.c:set_sec_ctx(326)
--------------
--
Denis Sbragion
InfoTecna
Tel: +39 0362 805396, Fax: +39 0362 805404
URL: http://www.infotecna.it