[Samba] Problem with listing a share

[Tobias von der Krone]

Hi,

I have a strange problem listing a share with, e.g.

# ls -al /path/to/share


I have a samba-client-3.5.4-68.el6_0.2.x86_64 on a Scientific Linux 6 
(as a virtual machine - if it matters) and an samba-3.0.33-0.30.el4 
server on a RHEL4. When I try the listed command I get the following in 
the servers logfile (on log lever = 3):

[2011/04/29 16:29:39, 3, effective(0, 0), real(0, 0)] smbd/process.c:process_smb(1083)

   Transaction 32 of length 78

[2011/04/29 16:29:39, 3, effective(0, 0), real(0, 0)] smbd/process.c:switch_message(932)

   switch message SMBtrans2 (pid 12215) conn 0x8f82e20

[2011/04/29 16:29:39, 3, effective(0, 0), real(0, 0)] smbd/sec_ctx.c:set_sec_ctx(241)

   setting sec ctx (872, 872) - sec_ctx_stack_ndx = 0

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/trans2.c:call_trans2qfilepathinfo(3304)

   call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(821)

   reduce_name [.] [/path/to/share]

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(922)

   reduce_name: . reduced to /path/to/share

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/trans2.c:call_trans2qfilepathinfo(3355)

   call_trans2qfilepathinfo . (fnum = -1) level=263 call=5 total_data=0

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/process.c:process_smb(1083)

   Transaction 33 of length 88

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/process.c:switch_message(932)

   switch message SMBtrans2 (pid 12215) conn 0x8f82e20

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/sec_ctx.c:set_sec_ctx(241)

   setting sec ctx (872, 872) - sec_ctx_stack_ndx = 0

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/trans2.c:call_trans2findfirst(1704)

   call_trans2findfirst: dirtype = 17, maxentries = 150,
close_after_first=0, close_if_end = 2 requires_resume_key = 4 level =
0x105, max_data_bytes = 16384

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(821)

   reduce_name [\*] [/path/to/share]

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(922)

   reduce_name: \* reduced to/path/to/share/\*

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(821)

   reduce_name [./] [/path/to/share]

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(922)

   reduce_name: ./ reduced to /path/to/share

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/dir.c:dptr_create(515)

   creating new dirptr 256 for path ./, expect_close = 1

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/error.c:error_packet_set(106)

   error packet at smbd/trans2.c(1906) cmd=50 (SMBtrans2) NT_STATUS_NO_SUCH_FILE

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/process.c:process_smb(1083)

   Transaction 34 of length 84

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/process.c:switch_message(932)

   switch message SMBtrans2 (pid 12215) conn 0x8f82e20

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/sec_ctx.c:set_sec_ctx(241)

   setting sec ctx (872, 872) - sec_ctx_stack_ndx = 0

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/trans2.c:call_trans2findnext(2018)

   call_trans2findnext: dirhandle = 0, max_data_bytes = 16384,
maxentries = 150, close_after_request=0, close_if_end = 2
requires_resume_key = 4 resume_key = 0 resume name =  continue=0 level =
  261

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/dir.c:dptr_fetch_lanman2(729)

   fetched null dirptr 0

[2011/04/29 16:29:39, 3, effective(872, 872), real(872, 0)] smbd/error.c:error_packet_set(106)

   error packet at smbd/trans2.c(2098) cmd=50 (SMBtrans2) STATUS_NO_MORE_FILES




The same command issued from another Scientific Linux 6 (hardware) 
machine works with the following servers log:

[2011/04/29 16:32:42, 3, effective(872, 872), real(872, 0)] smbd/process.c:process_smb(1083)

   Transaction 7 of length 78

[2011/04/29 16:32:42, 3, effective(872, 872), real(872, 0)] smbd/process.c:switch_message(932)

   switch message SMBtrans2 (pid 26105) conn 0x90cc218

[2011/04/29 16:32:42, 3, effective(872, 872), real(872, 0)] smbd/sec_ctx.c:set_sec_ctx(241)

   setting sec ctx (872, 872) - sec_ctx_stack_ndx = 0

[2011/04/29 16:32:42, 3, effective(872, 872), real(872, 0)] smbd/trans2.c:call_trans2qfilepathinfo(3304)

   call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263

[2011/04/29 16:32:42, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(821)

   reduce_name [.] [/path/to/share]

[2011/04/29 16:32:42, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(922)

   reduce_name: . reduced to /path/to/share

[2011/04/29 16:32:42, 3, effective(872, 872), real(872, 0)] smbd/trans2.c:call_trans2qfilepathinfo(3355)

   call_trans2qfilepathinfo . (fnum = -1) level=263 call=5 total_data=0

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/process.c:process_smb(1083)

   Transaction 8 of length 78

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/process.c:switch_message(932)

   switch message SMBtrans2 (pid 26105) conn 0x90cc218

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/sec_ctx.c:set_sec_ctx(241)

   setting sec ctx (872, 872) - sec_ctx_stack_ndx = 0

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/trans2.c:call_trans2qfilepathinfo(3304)

   call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(821)

   reduce_name [.] [/path/to/share]

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(922)

   reduce_name: . reduced to /path/to/share

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/trans2.c:call_trans2qfilepathinfo(3355)

   call_trans2qfilepathinfo . (fnum = -1) level=263 call=5 total_data=0

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/process.c:process_smb(1083)

   Transaction 9 of length 88

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/process.c:switch_message(932)

   switch message SMBtrans2 (pid 26105) conn 0x90cc218

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/sec_ctx.c:set_sec_ctx(241)

   setting sec ctx (872, 872) - sec_ctx_stack_ndx = 0

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/trans2.c:call_trans2findfirst(1704)

   call_trans2findfirst: dirtype = 17, maxentries = 150,
close_after_first=0, close_if_end = 2 requires_resume_key = 4 level =
0x105, max_data_bytes = 16384

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(821)

   reduce_name [*] [/path/to/share]

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(922)

   reduce_name: * reduced to/path/to/share/*

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(821)

   reduce_name [./] [/path/to/share]

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/vfs.c:reduce_name(922)

   reduce_name: ./ reduced to /path/to/share

[2011/04/29 16:32:43, 3, effective(872, 872), real(872, 0)] smbd/dir.c:dptr_create(515)

   creating new dirptr 256 for path ./, expect_close = 1



What I find strange is that on the failing client the second transaction

Transaction 33 of length 88

has another length whereas the length of the second transaction on the 
successful client is equal.

Another symptom is when I create a file on the failing client the 
filename gets a leading '\' (backslash) on the server and the client can 
see this file (with the backslash).

Does anyone has an idea? Do you need more information?

By Tobias