[Samba] XP irratic delay in opening files....

[Justin Anderson]

Hi all

I am fairly new to samba and XP. I have a client who we set up a samba
server for, they are complaining about documents taking about 30 secs
-2-3mins to open but only sometimes (it can be very frequent). I have
seen this and captured a piece of the log file I wonder if any one can
make any sense of it...

I have been looking on the net and trying various things all day but to
no avail...



2003/02/24 15:53:42, 3] smbd/trans2.c:call_trans2qfilepathinfo(1560)
  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 257
[2003/02/24 15:53:42, 3] smbd/trans2.c:call_trans2qfilepathinfo(1646)
  call_trans2qfilepathinfo Saranes_Documents/NJC/Invoices/NJC Invoice25
R001.xls level=257 call=7 total_data=0
[2003/02/24 15:53:42, 3] smbd/process.c:process_smb(860)
  Transaction 7344 of length 120
[2003/02/24 15:53:42, 3] smbd/process.c:switch_message(667)
  switch message SMBtrans2 (pid 10033)
[2003/02/24 15:53:42, 3] smbd/sec_ctx.c:set_sec_ctx(313)
  setting sec ctx (99, 99) - sec_ctx_stack_ndx = 0
[2003/02/24 15:53:42, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  99
[2003/02/24 15:53:42, 3] smbd/trans2.c:call_trans2setfilepathinfo(2350)
  call_trans2setfilepathinfo(8) Saranes_Documents/NJC/Invoices/NJC
Invoice25 R001.xls info_level=257 totdata=40
[2003/02/24 15:53:42, 3] smbd/process.c:process_smb(860)
  Transaction 7345 of length 45
[2003/02/24 15:53:42, 3] smbd/process.c:switch_message(667)
  switch message SMBclose (pid 10033)
[2003/02/24 15:53:42, 3] smbd/sec_ctx.c:set_sec_ctx(313)
  setting sec ctx (99, 99) - sec_ctx_stack_ndx = 0
[2003/02/24 15:53:42, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  99
[2003/02/24 15:53:42, 3] smbd/reply.c:reply_close(2926)
  close fd=22 fnum=12979 (numopen=2)
[2003/02/24 15:53:42, 2] smbd/close.c:close_normal_file(210)
  nobody closed file Saranes_Documents/NJC/Invoices/NJC Invoice25
R001.xls (numopen=1)
[2003/02/24 15:53:42, 3] smbd/process.c:process_smb(860)
  Transaction 7346 of length 142
[2003/02/24 15:53:42, 3] smbd/process.c:switch_message(667)
  switch message SMBntcreateX (pid 10033)
[2003/02/24 15:53:42, 3] smbd/sec_ctx.c:set_sec_ctx(313)
  setting sec ctx (99, 99) - sec_ctx_stack_ndx = 0
[2003/02/24 15:53:42, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  99
[2003/02/24 15:53:42, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/Saranes_Documents/NJC/Invoices/NJC Invoice25
R001.xls]
[2003/02/24 15:53:42, 3] smbd/dosmode.c:unix_mode(111)
  unix_mode(Saranes_Documents/NJC/Invoices/NJC Invoice25 R001.xls)
returning 0744
[2003/02/24 15:53:42, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [Saranes_Documents/NJC/Invoices/NJC Invoice25
R001.xls]
[2003/02/24 15:53:42, 4] smbd/open.c:open_file_shared(778)
  calling open_file with flags=0x0 flags2=0x0 mode=0744
[2003/02/24 15:53:42, 2] smbd/open.c:open_file(213)
  brigitte opened file Saranes_Documents/NJC/Invoices/NJC Invoice25
R001.xls read=Yes write=No (numopen=2)
[2003/02/24 15:53:42, 3]
smbd/oplock_linux.c:linux_set_kernel_oplock(182)
  set_file_oplock: got kernel oplock on file
Saranes_Documents/NJC/Invoices/NJC Invoice25 R001.xls, dev = 903, inode
= 13385874, file_id = 2048
[2003/02/24 15:53:42, 3] smbd/process.c:process_smb(860)
  Transaction 7347 of length 63
[2003/02/24 15:53:42, 3] smbd/process.c:switch_message(667)
  switch message SMBreadX (pid 10033)
[2003/02/24 15:53:42, 3] smbd/sec_ctx.c:set_sec_ctx(313)
  setting sec ctx (99, 99) - sec_ctx_stack_ndx = 0
[2003/02/24 15:53:42, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  99
[2003/02/24 15:53:42, 3] smbd/reply.c:reply_read_and_X(2381)
  readX fnum=12980 min=4096 max=4096 nread=4096
[2003/02/24 15:53:42, 3] smbd/process.c:process_smb(860)
  Transaction 7348 of length 142
[2003/02/24 15:53:42, 3] smbd/process.c:switch_message(667)
  switch message SMBntcreateX (pid 10033)
[2003/02/24 15:53:42, 3] smbd/sec_ctx.c:set_sec_ctx(313)
  setting sec ctx (99, 99) - sec_ctx_stack_ndx = 0
[2003/02/24 15:53:42, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  99
[2003/02/24 15:53:42, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/Saranes_Documents/NJC/Invoices/NJC Invoice25
R001.xls]
[2003/02/24 15:53:42, 3] smbd/dosmode.c:unix_mode(111)
  unix_mode(Saranes_Documents/NJC/Invoices/NJC Invoice25 R001.xls)
returning 0744
[2003/02/24 15:53:42, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [Saranes_Documents/NJC/Invoices/NJC Invoice25
R001.xls]
[2003/02/24 15:53:42, 3] smbd/oplock.c:initial_break_processing(491)
  initial_break_processing: called for dev = 903, inode = 13385874
file_id = 2048
  Current oplocks_open (exclusive = 1, levelII = 0)
[2003/02/24 15:53:42, 3] smbd/sec_ctx.c:set_sec_ctx(313)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/02/24 15:53:42, 3] smbd/oplock.c:initial_break_processing(491)
  initial_break_processing: called for dev = 903, inode = 13385874
file_id = 2048
  Current oplocks_open (exclusive = 1, levelII = 0)
[2003/02/24 15:53:42, 3] smbd/process.c:process_smb(860)
  Transaction 7348 of length 76
[2003/02/24 15:53:42, 3] smbd/process.c:switch_message(667)
  switch message SMBtrans2 (pid 10033)
[2003/02/24 15:53:42, 2] smbd/process.c:switch_message(678)
  switch_message: queueing message due to being in oplock break state.
[2003/02/24 15:53:42, 3] smbd/oplock.c:initial_break_processing(491)
  initial_break_processing: called for dev = 903, inode = 13385874
file_id = 2048
  Current oplocks_open (exclusive = 1, levelII = 0)

This is the point at which it waits for some time....


Thanks in advance 
Justin Anderson