[Samba] 3.3.3 blocks writing of files ~1.55GB or greater from Windows XP

Nikkos Svoboda nsvobo1 at lsu.edu
Tue Apr 21 14:40:01 GMT 2009


On Sun, Apr 19, 2009 at 2:17 AM, Volker Lendecke
<Volker.Lendecke at sernet.de>wrote:

> On Sat, Apr 18, 2009 at 10:35:14PM -0500, Nikkos Svoboda wrote:
> >    Samba is hosted on a server running Debian-Lenny, on a Reiserfs
> > partition.
> >
> >   After upgrading to 3.3.3, Windows XP clients fail to copy files
> > through explorer using SMB shares when the file size is around 1.55GB or
> > greater in size. The error is "Cannot copy Filename.ext: The specified
> > network name is no longer available." Copying existing files located on
> > the share to the same or any other share fails with the same error.
> > Copying files from the share to the local client filesystem works
> > correctly. Linux and Mac clients have no problems. I have not tested
> > Vista at this point.
> >
> >   The error occurs before any copying begins. The target folder on the
> > share will contain a correctly-named, 0 byte file after the attempt to
> > write is made. The POSIX permissions of the 0-byte file on the server
> > side are correct and the file is owned by the correct user : group.
> >
> >   I do not believe this is related to the error dealing with the
> > rotation between ports 139 and 445. The copy does not fail after some
> > time, it is immediate. To be sure, I have tried setting both 445 and 139
> > alone as the option "smb ports = ".
> >
> >   Is anyone else seeing this behavior?
>
> Can we see a debug level 10 log of smbd of this problem?
>
> Thanks,
>
> Volker



   The logfiles on the Samba server are split by username/hostname. Here is
a client logfile of an attempt to copy a 2GB file to an empty folder the
share ( "\\shareserver\Sambatest\2GBtestfile.largetest" ). There is also an
"smbd" logfile, which contains only the statement "getpeername failed. Error
was Transport endpoint is not connected" repeated. The series of events for
this copy compared to a successful copy seem quite different.

-Nikkos Svoboda
-------------- next part --------------
[2009/04/20 12:51:10, 10] lib/util.c:dump_data(2233)
  [000] 00 5C 00 53 00 61 00 6D  00 62 00 61 00 74 00 65  .\.S.a.m .b.a.t.e
  [010] 00 73 00 74 00 5C 00 32  00 47 00 42 00 74 00 65  .s.t.\.2 .G.B.t.e
  [020] 00 73 00 74 00 66 00 69  00 6C 00 65 00 2E 00 6C  .s.t.f.i .l.e...l
  [030] 00 61 00 72 00 67 00 65  00 74 00 65 00 73 00 74  .a.r.g.e .t.e.s.t
  [040] 00 00 00                                          ... 
[2009/04/20 12:51:10,  3] smbd/process.c:switch_message(1378)
  switch message SMBntcreateX (pid 12451) conn 0x86e1cb8
[2009/04/20 12:51:10,  3] smbd/sec_ctx.c:set_sec_ctx(324)
  setting sec ctx (XXXXXXXXXX, XXXXXXXXXX) - sec_ctx_stack_ndx = 0
[2009/04/20 12:51:10,  5] auth/token_util.c:debug_nt_user_token(528)
  NT user token of user XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
  contains 16 SIDs
  SID[  0]: XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
  SID[  1]: XXXXXXXXXX
  SID[  2]: XXXXXXXXXX
  SID[  3]: XXXXXXXXXX
  SID[  4]: XXXXXXXXXX
  SID[  5]: XXXXXXXXXX
  SID[  6]: XXXXXXXXXX
  SID[  7]: XXXXXXXXXX
  SID[  8]: XXXXXXXXXX
  SID[  9]: XXXXXXXXXX
  SID[ 10]: XXXXXXXXXX
  SID[ 11]: XXXXXXXXXX
  SID[ 12]: XXXXXXXXXX
  SID[ 13]: XXXXXXXXXX
  SID[ 14]: XXXXXXXXXX
  SID[ 15]: XXXXXXXXXX
  SE_PRIV  0x0 0x0 0x0 0x0
[2009/04/20 12:51:10,  5] auth/token_util.c:debug_unix_user_token(548)
  UNIX token of user XXXXXXX
  Primary group is 4 and contains 11 supplementary groups
  Group[  0]: XXXXXXXXXX
  Group[  1]: XXXXXXXXXX
  Group[  2]: XXXXXXXXXX
  Group[  3]: XXXXXXXXXX
  Group[  4]: XXXXXXXXXX
  Group[  5]: XXXXXXXXXX
  Group[  6]: XXXXXXXXXX
  Group[  7]: XXXXXXXXXX
  Group[  8]: XXXXXXXXXX
  Group[  9]: XXXXXXXXXX
  Group[ 10]: XXXXXXXXXX
[2009/04/20 12:51:10,  5] smbd/uid.c:change_to_user(303)
  change_to_user uid=(XXXXXXXXXX,XXXXXXXXXX) gid=(XXXXXXXXXX,XXXXXXXXXX)
[2009/04/20 12:51:10,  4] smbd/vfs.c:vfs_ChDir(733)
  vfs_ChDir to XXXXXXXXXX ( ### edit - user home directory ### )
[2009/04/20 12:51:10, 10] smbd/nttrans.c:reply_ntcreate_and_X(454)
  reply_ntcreate_and_X: flags = 0x16, access_mask = 0x20189 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x940 root_dir_fid = 0x0, fname = Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10, 10] smbd/open.c:create_file(3283)
  create_file: access_mask = 0x20189 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x940 oplock_request = 0x3 root_dir_fid = 0x0, ea_list = 0x(nil), sd = 0x(nil), fname = Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  5] smbd/filename.c:unix_convert(148)
  unix_convert called on file "Sambatest/2GBtestfile.largetest"
[2009/04/20 12:51:10, 10] smbd/statcache.c:stat_cache_lookup(235)
  stat_cache_lookup: lookup failed for name [SAMBATEST/2GBTESTFILE.LARGETEST]
[2009/04/20 12:51:10, 10] smbd/statcache.c:stat_cache_lookup(235)
  stat_cache_lookup: lookup failed for name [SAMBATEST]
[2009/04/20 12:51:10,  5] smbd/statcache.c:stat_cache_add(138)
  stat_cache_add: Added entry (86f0608:size 1f) SAMBATEST/2GBTESTFILE.LARGETEST -> Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  5] smbd/filename.c:unix_convert(297)
  conversion finished Sambatest/2GBtestfile.largetest -> Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10, 10] smbd/open.c:create_file_unixpath(2889)
  create_file_unixpath: access_mask = 0x20189 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x940 oplock_request = 0x3 ea_list = 0x(nil), sd = 0x(nil), fname = Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  5] smbd/files.c:file_new(121)
  allocated file structure 7741, fnum = 11837 (1 used)
[2009/04/20 12:51:10,  3] smbd/dosmode.c:unix_mode(124)
  unix_mode(Sambatest/2GBtestfile.largetest) returning 0644
[2009/04/20 12:51:10, 10] smbd/open.c:open_file_ntcreate_internal(1447)
  open_file_ntcreate: fname=Sambatest/2GBtestfile.largetest, dos_attrs=0x80 access_mask=0x20189 share_access=0x7 create_disposition = 0x1 create_options=0x940 unix mode=0644 oplock_request=3
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode(361)
  dos_mode: Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
  dos_mode_from_sbuf returning 
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode(403)
  dos_mode returning 
[2009/04/20 12:51:10, 10] smbd/open.c:open_file_ntcreate_internal(1625)
  open_file_ntcreate: fname=Sambatest/2GBtestfile.largetest, after mapping access_mask=0x20189
[2009/04/20 12:51:10, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
  Locking key 1108000000000000BA99
[2009/04/20 12:51:10, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
  Allocated locked data 0x0x86f07e8
[2009/04/20 12:51:10, 10] locking/locking.c:parse_share_modes(519)
  parse_share_modes: delete_on_close: 0, owrt: Mon 20 Apr 2009 12:51:09 PM CDT CDT, cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num_share_modes: 1
[2009/04/20 12:51:10, 10] locking/locking.c:parse_share_modes(616)
  parse_share_modes: share_mode_entry[0]:  pid = 12352, share_access = 0x0, private_options = 0x44, access_mask = 0x30196, mid = 0x0, type= 0x3, gen_id = 115, uid = XXXXXXXXXX, flags = 0, file_id 811:4199ba
[2009/04/20 12:51:10, 10] locking/locking.c:parse_share_modes(619)
  parse_share_modes: deleted share_mode_entry[0]:  pid = 12352, share_access = 0x0, private_options = 0x44, access_mask = 0x30196, mid = 0x0, type= 0x3, gen_id = 115, uid = XXXXXXXXXX, flags = 0, file_id 811:4199ba
[2009/04/20 12:51:10, 10] smbd/open.c:delay_for_oplocks(952)
  delay_for_oplocks: oplock type 0x3 on file 
[2009/04/20 12:51:10, 10] smbd/open.c:delay_for_oplocks(952)
  delay_for_oplocks: oplock type 0x3 on file 
[2009/04/20 12:51:10,  4] smbd/open.c:open_file_ntcreate_internal(1884)
  calling open_file with flags=0x0 flags2=0x0 mode=0644, access_mask = 0x20189, open_access_mask = 0x20189
[2009/04/20 12:51:10, 10] smbd/open.c:fd_open(131)
  fd_open: name Sambatest/2GBtestfile.largetest, flags = 00 mode = 0644, fd = 28. 
[2009/04/20 12:51:10,  2] smbd/open.c:open_file(551)
  XXXXXXXXXX opened file Sambatest/2GBtestfile.largetest read=Yes write=No (numopen=1)
[2009/04/20 12:51:10,  3] smbd/oplock_linux.c:linux_set_kernel_oplock(145)
  linux_set_kernel_oplock: got kernel oplock on file Sambatest/2GBtestfile.largetest, file_id = 811:4199ba gen_id = 1
[2009/04/20 12:51:10,  5] smbd/oplock.c:set_file_oplock(126)
  set_file_oplock: granted oplock on file Sambatest/2GBtestfile.largetest, 811:4199ba/1, tv_sec = 49ecb60e, tv_usec = 1f34
[2009/04/20 12:51:10, 10] locking/locking.c:unparse_share_modes(683)
  unparse_share_modes: del: 0, owrt: Mon 20 Apr 2009 12:51:09 PM CDT CDT cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num: 1
[2009/04/20 12:51:10, 10] locking/locking.c:print_share_mode_table(485)
  print_share_mode_table: share_mode_entry[0]:  pid = 12451, share_access = 0x7, private_options = 0x940, access_mask = 0x20189, mid = 0x0, type= 0x3, gen_id = 1, uid = XXXXXXXXXX, flags = 0, file_id 811:4199ba
[2009/04/20 12:51:10, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
  Unlocking key 1108000000000000BA99
[2009/04/20 12:51:10, 10] smbd/open.c:create_file_unixpath(3209)
  create_file_unixpath: info=1
[2009/04/20 12:51:10, 10] smbd/open.c:create_file(3472)
  create_file: info=1
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode(361)
  dos_mode: Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
  dos_mode_from_sbuf returning 
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode(403)
  dos_mode returning 
[2009/04/20 12:51:10, 10] smbd/file_access.c:can_access_file_data(147)
  can_access_file_data: requesting 0x2 on file Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  5] smbd/nttrans.c:reply_ntcreate_and_X(607)
  reply_ntcreate_and_X: fnum = 11837, open name = Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=135
  smb_com=0xa2
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=45632
  smt_wct=42
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=15618 (0x3D02)
  smb_vwv[ 3]=  302 (0x12E)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=32768 (0x8000)
  smb_vwv[ 6]=64804 (0xFD24)
  smb_vwv[ 7]=57493 (0xE095)
  smb_vwv[ 8]=51649 (0xC9C1)
  smb_vwv[ 9]=32769 (0x8001)
  smb_vwv[10]=64804 (0xFD24)
  smb_vwv[11]=57493 (0xE095)
  smb_vwv[12]=51649 (0xC9C1)
  smb_vwv[13]=32769 (0x8001)
  smb_vwv[14]=64804 (0xFD24)
  smb_vwv[15]=57493 (0xE095)
  smb_vwv[16]=51649 (0xC9C1)
  smb_vwv[17]=32769 (0x8001)
  smb_vwv[18]=64804 (0xFD24)
  smb_vwv[19]=57493 (0xE095)
  smb_vwv[20]=51649 (0xC9C1)
  smb_vwv[21]=32769 (0x8001)
  smb_vwv[22]=    0 (0x0)
  smb_vwv[23]=    0 (0x0)
  smb_vwv[24]=    0 (0x0)
  smb_vwv[25]=    0 (0x0)
  smb_vwv[26]=    0 (0x0)
  smb_vwv[27]=    0 (0x0)
  smb_vwv[28]=    0 (0x0)
  smb_vwv[29]=    0 (0x0)
  smb_vwv[30]=    0 (0x0)
  smb_vwv[31]=    0 (0x0)
  smb_vwv[32]= 1792 (0x700)
  smb_vwv[33]=    0 (0x0)
  smb_vwv[34]=    0 (0x0)
  smb_vwv[35]=    0 (0x0)
  smb_vwv[36]=    0 (0x0)
  smb_vwv[37]=    0 (0x0)
  smb_vwv[38]=    0 (0x0)
  smb_vwv[39]=    0 (0x0)
  smb_vwv[40]=    0 (0x0)
  smb_vwv[41]=    0 (0x0)
  smb_bcc=0
[2009/04/20 12:51:10, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
  got smb length of 72
[2009/04/20 12:51:10,  6] smbd/process.c:process_smb(1551)
  got message type 0x0 of len 0x48
[2009/04/20 12:51:10,  3] smbd/process.c:process_smb(1554)
  Transaction 5 of length 76 (0 toread)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=72
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=45696
  smt_wct=15
  smb_vwv[ 0]=    4 (0x4)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]=   40 (0x28)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    4 (0x4)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    7 (0x7)
  smb_bcc=7
[2009/04/20 12:51:10, 10] lib/util.c:dump_data(2233)
  [000] 00 00 00 3D 2E EC 03                              ...=... 
[2009/04/20 12:51:10,  3] smbd/process.c:switch_message(1378)
  switch message SMBtrans2 (pid 12451) conn 0x86e1cb8
[2009/04/20 12:51:10,  4] smbd/uid.c:change_to_user(213)
  change_to_user: Skipping user change - already user
[2009/04/20 12:51:10,  3] smbd/trans2.c:call_trans2qfilepathinfo(3867)
  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1004
[2009/04/20 12:51:10, 10] locking/locking.c:parse_share_modes(519)
  parse_share_modes: delete_on_close: 0, owrt: Mon 20 Apr 2009 12:51:09 PM CDT CDT, cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num_share_modes: 1
[2009/04/20 12:51:10, 10] locking/locking.c:parse_share_modes(616)
  parse_share_modes: share_mode_entry[0]:  pid = 12451, share_access = 0x7, private_options = 0x940, access_mask = 0x20189, mid = 0x0, type= 0x3, gen_id = 1, uid = XXXXXXXXXX, flags = 0, file_id 811:4199ba
[2009/04/20 12:51:10,  3] smbd/trans2.c:call_trans2qfilepathinfo(4057)
  call_trans2qfilepathinfo Sambatest/2GBtestfile.largetest (fnum = 11837) level=1004 call=7 total_data=0
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode(361)
  dos_mode: Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
  dos_mode_from_sbuf returning 
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode(403)
  dos_mode returning 
[2009/04/20 12:51:10, 10] smbd/trans2.c:call_trans2qfilepathinfo(4299)
  call_trans2qfilepathinfo: SMB_FILE_BASIC_INFORMATION
[2009/04/20 12:51:10,  5] smbd/trans2.c:call_trans2qfilepathinfo(4309)
  SMB_QFBI - create: Mon Apr 20 12:51:09 2009
   access: Mon Apr 20 12:51:09 2009
   write: Mon Apr 20 12:51:09 2009
   change: Mon Apr 20 12:51:09 2009
   mode: 80
[2009/04/20 12:51:10,  9] smbd/trans2.c:send_trans2_replies(840)
  t2_rep: params_sent_thistime = 2, data_sent_thistime = 40, useable_space = 131010
[2009/04/20 12:51:10,  9] smbd/trans2.c:send_trans2_replies(842)
  t2_rep: params_to_send = 2, data_to_send = 40, paramsize = 2, datasize = 40
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=100
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=45696
  smt_wct=10
  smb_vwv[ 0]=    2 (0x2)
  smb_vwv[ 1]=   40 (0x28)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    2 (0x2)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=   40 (0x28)
  smb_vwv[ 7]=   60 (0x3C)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=45
[2009/04/20 12:51:10, 10] lib/util.c:dump_data(2233)
  [000] 00 00 00 00 00 80 24 FD  95 E0 C1 C9 01 80 24 FD  ......$. ......$.
  [010] 95 E0 C1 C9 01 80 24 FD  95 E0 C1 C9 01 80 24 FD  ......$. ......$.
  [020] 95 E0 C1 C9 01 80 00 00  00 00 00 00 00           ........ .....
[2009/04/20 12:51:10, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
  got smb length of 116
[2009/04/20 12:51:10,  6] smbd/process.c:process_smb(1551)
  got message type 0x0 of len 0x74
[2009/04/20 12:51:10,  3] smbd/process.c:process_smb(1554)
  Transaction 6 of length 120 (0 toread)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=116
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=45760
  smt_wct=15
  smb_vwv[ 0]=    6 (0x6)
  smb_vwv[ 1]=   40 (0x28)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]=    0 (0x0)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    6 (0x6)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=   40 (0x28)
  smb_vwv[12]=   76 (0x4C)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    8 (0x8)
  smb_bcc=51
[2009/04/20 12:51:10, 10] lib/util.c:dump_data(2233)
  [000] 00 00 00 3D 2E EC 03 00  00 00 00 FF FF FF FF FF  ...=.... ........
  [010] FF FF FF FF FF FF FF FF  FF FF FF FF FF FF FF FF  ........ ........
  [020] FF FF FF FF FF FF FF FF  FF FF FF 00 00 00 00 00  ........ ........
  [030] 00 00 00                                          ... 
[2009/04/20 12:51:10,  3] smbd/process.c:switch_message(1378)
  switch message SMBtrans2 (pid 12451) conn 0x86e1cb8
[2009/04/20 12:51:10,  4] smbd/uid.c:change_to_user(213)
  change_to_user: Skipping user change - already user
[2009/04/20 12:51:10,  3] smbd/trans2.c:call_trans2setfilepathinfo(6790)
  call_trans2setfilepathinfo(8) Sambatest/2GBtestfile.largetest (fnum 11837) info_level=1004 totdata=40
[2009/04/20 12:51:10,  6] smbd/trans2.c:smb_set_file_dosmode(4999)
  smb_set_file_dosmode: dosmode: 0x0
[2009/04/20 12:51:10, 10] smbd/trans2.c:smb_set_file_basic_info(5751)
  smb_set_file_basic_info: file Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  6] smbd/trans2.c:smb_set_file_time(4912)
  smb_set_file_time: actime: Mon Apr 20 12:51:09 2009
   smb_set_file_time: modtime: Mon Apr 20 12:51:09 2009
   t2_rep: params_sent_thistime = 2, data_sent_thistime = 0, useable_space = 131012
[2009/04/20 12:51:10,  9] smbd/trans2.c:send_trans2_replies(842)
  t2_rep: params_to_send = 2, data_to_send = 0, paramsize = 2, datasize = 0
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=58
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=45760
  smt_wct=10
  smb_vwv[ 0]=    2 (0x2)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    2 (0x2)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=3
[2009/04/20 12:51:10, 10] lib/util.c:dump_data(2233)
  [000] 00 00 00                                          ... 
[2009/04/20 12:51:10, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
  got smb length of 41
[2009/04/20 12:51:10,  6] smbd/process.c:process_smb(1551)
  got message type 0x0 of len 0x29
[2009/04/20 12:51:10,  3] smbd/process.c:process_smb(1554)
  Transaction 7 of length 45 (0 toread)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=41
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=65279
  smb_uid=XXXXXXXXXX
  smb_mid=45824
  smt_wct=3
  smb_vwv[ 0]=11837 (0x2E3D)
  smb_vwv[ 1]=65535 (0xFFFF)
  smb_vwv[ 2]=65535 (0xFFFF)
  smb_bcc=0
[2009/04/20 12:51:10,  3] smbd/process.c:switch_message(1378)
  switch message SMBclose (pid 12451) conn 0x86e1cb8
[2009/04/20 12:51:10,  4] smbd/uid.c:change_to_user(213)
  change_to_user: Skipping user change - already user
[2009/04/20 12:51:10,  3] smbd/reply.c:reply_close(4353)
  close fd=28 fnum=11837 (numopen=1)
[2009/04/20 12:51:10,  6] smbd/close.c:set_close_write_time(450)
  close_write_time: Wed Dec 31 17:59:59 1969
[2009/04/20 12:51:10, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
  Locking key 1108000000000000BA99
[2009/04/20 12:51:10, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
  Allocated locked data 0x0x86f0498
[2009/04/20 12:51:10, 10] locking/locking.c:parse_share_modes(519)
  parse_share_modes: delete_on_close: 0, owrt: Mon 20 Apr 2009 12:51:09 PM CDT CDT, cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num_share_modes: 1
[2009/04/20 12:51:10, 10] locking/locking.c:parse_share_modes(616)
  parse_share_modes: share_mode_entry[0]:  pid = 12451, share_access = 0x7, private_options = 0x940, access_mask = 0x20189, mid = 0x0, type= 0x3, gen_id = 1, uid = XXXXXXXXXX, flags = 0, file_id 811:4199ba
[2009/04/20 12:51:10, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
  Unlocking key 1108000000000000BA99
[2009/04/20 12:51:10, 10] smbd/oplock_linux.c:linux_release_kernel_oplock(156)
  linux_release_kernel_oplock: file Sambatest/2GBtestfile.largetest, file_id = 811:4199ba gen_id = 1 has kernel oplock state of 1.
[2009/04/20 12:51:10, 10] locking/posix.c:get_windows_lock_ref_count(495)
  get_windows_lock_count for file Sambatest/2GBtestfile.largetest = 0
[2009/04/20 12:51:10, 10] locking/posix.c:delete_windows_lock_ref_count(521)
  delete_windows_lock_ref_count for file Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  2] smbd/close.c:close_normal_file(606)
  XXXXXXXXXX closed file Sambatest/2GBtestfile.largetest (numopen=0) NT_STATUS_OK
[2009/04/20 12:51:10,  5] smbd/files.c:file_free(471)
  freed files structure 11837 (0 used)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=35
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=65279
  smb_uid=XXXXXXXXXX
  smb_mid=45824
  smt_wct=0
  smb_bcc=0
[2009/04/20 12:51:10, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
  got smb length of 150
[2009/04/20 12:51:10,  6] smbd/process.c:process_smb(1551)
  got message type 0x0 of len 0x96
[2009/04/20 12:51:10,  3] smbd/process.c:process_smb(1554)
  Transaction 8 of length 154 (0 toread)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=150
  smb_com=0xa2
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=45888
  smt_wct=24
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=57054 (0xDEDE)
  smb_vwv[ 2]=16384 (0x4000)
  smb_vwv[ 3]= 4096 (0x1000)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=32768 (0x8000)
  smb_vwv[ 8]=    1 (0x1)
  smb_vwv[ 9]=    0 (0x0)
  smb_vwv[10]=    0 (0x0)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    0 (0x0)
  smb_vwv[14]=    0 (0x0)
  smb_vwv[15]= 1792 (0x700)
  smb_vwv[16]=    0 (0x0)
  smb_vwv[17]=  256 (0x100)
  smb_vwv[18]=    0 (0x0)
  smb_vwv[19]=    0 (0x0)
  smb_vwv[20]=    0 (0x0)
  smb_vwv[21]=  512 (0x200)
  smb_vwv[22]=    0 (0x0)
  smb_vwv[23]=    0 (0x0)
  smb_bcc=67
[2009/04/20 12:51:10, 10] lib/util.c:dump_data(2233)
  [000] 00 5C 00 53 00 61 00 6D  00 62 00 61 00 74 00 65  .\.S.a.m .b.a.t.e
  [010] 00 73 00 74 00 5C 00 32  00 47 00 42 00 74 00 65  .s.t.\.2 .G.B.t.e
  [020] 00 73 00 74 00 66 00 69  00 6C 00 65 00 2E 00 6C  .s.t.f.i .l.e...l
  [030] 00 61 00 72 00 67 00 65  00 74 00 65 00 73 00 74  .a.r.g.e .t.e.s.t
  [040] 00 00 00                                          ... 
[2009/04/20 12:51:10,  3] smbd/process.c:switch_message(1378)
  switch message SMBntcreateX (pid 12451) conn 0x86e1cb8
[2009/04/20 12:51:10,  4] smbd/uid.c:change_to_user(213)
  change_to_user: Skipping user change - already user
[2009/04/20 12:51:10, 10] smbd/nttrans.c:reply_ntcreate_and_X(454)
  reply_ntcreate_and_X: flags = 0x10, access_mask = 0x180 file_attributes = 0x0, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 root_dir_fid = 0x0, fname = Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10, 10] smbd/open.c:create_file(3283)
  create_file: access_mask = 0x180 file_attributes = 0x0, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 oplock_request = 0x0 root_dir_fid = 0x0, ea_list = 0x(nil), sd = 0x(nil), fname = Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  5] smbd/filename.c:unix_convert(148)
  unix_convert called on file "Sambatest/2GBtestfile.largetest"
[2009/04/20 12:51:10, 10] smbd/statcache.c:stat_cache_lookup(274)
  stat_cache_lookup: lookup succeeded for name [SAMBATEST/2GBTESTFILE.LARGETEST] -> [Sambatest/2GBtestfile.largetest]
[2009/04/20 12:51:10, 10] smbd/open.c:create_file_unixpath(2889)
  create_file_unixpath: access_mask = 0x180 file_attributes = 0x0, share_access = 0x7, create_disposition = 0x1 create_options = 0x0 oplock_request = 0x0 ea_list = 0x(nil), sd = 0x(nil), fname = Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  5] smbd/files.c:file_new(121)
  allocated file structure 7742, fnum = 11838 (1 used)
[2009/04/20 12:51:10,  3] smbd/dosmode.c:unix_mode(124)
  unix_mode(Sambatest/2GBtestfile.largetest) returning 0644
[2009/04/20 12:51:10, 10] smbd/open.c:open_file_ntcreate_internal(1447)
  open_file_ntcreate: fname=Sambatest/2GBtestfile.largetest, dos_attrs=0x0 access_mask=0x180 share_access=0x7 create_disposition = 0x1 create_options=0x0 unix mode=0644 oplock_request=0
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode(361)
  dos_mode: Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
  dos_mode_from_sbuf returning 
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode(403)
  dos_mode returning 
[2009/04/20 12:51:10, 10] smbd/open.c:open_file_ntcreate_internal(1625)
  open_file_ntcreate: fname=Sambatest/2GBtestfile.largetest, after mapping access_mask=0x180
[2009/04/20 12:51:10, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
  Locking key 1108000000000000BA99
[2009/04/20 12:51:10, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
  Allocated locked data 0x0x86f0738
[2009/04/20 12:51:10,  4] smbd/open.c:open_file_ntcreate_internal(1884)
  calling open_file with flags=0x0 flags2=0x0 mode=0644, access_mask = 0x180, open_access_mask = 0x180
[2009/04/20 12:51:10, 10] smbd/posix_acls.c:posix_get_nt_acl(3115)
  posix_get_nt_acl: called for file Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10, 10] lib/gencache.c:gencache_get(194)
  Cache entry with key = IDMAP/UID2SID/XXXXXXXXXX couldn't be found
[2009/04/20 12:51:10,  5] passdb/lookup_sid.c:uid_to_sid(1322)
  uid_to_sid: winbind failed to find a sid for uid XXXXXXXXXX
[2009/04/20 12:51:10,  3] smbd/sec_ctx.c:push_sec_ctx(224)
  push_sec_ctx(XXXXXXXXXX, XXXXXXXXXX) : sec_ctx_stack_ndx = 1
[2009/04/20 12:51:10,  3] smbd/uid.c:push_conn_ctx(388)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
[2009/04/20 12:51:10,  3] smbd/sec_ctx.c:set_sec_ctx(324)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2009/04/20 12:51:10,  5] auth/token_util.c:debug_nt_user_token(522)
  NT user token: (NULL)
[2009/04/20 12:51:10,  5] auth/token_util.c:debug_unix_user_token(548)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2009/04/20 12:51:10,  3] smbd/sec_ctx.c:push_sec_ctx(224)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2009/04/20 12:51:10,  3] smbd/uid.c:push_conn_ctx(388)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 1
[2009/04/20 12:51:10,  3] smbd/sec_ctx.c:set_sec_ctx(324)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2009/04/20 12:51:10,  5] auth/token_util.c:debug_nt_user_token(522)
  NT user token: (NULL)
[2009/04/20 12:51:10,  5] auth/token_util.c:debug_unix_user_token(548)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups

( ### edit - large group of messages about parsing pasword file ### )

  endsmbfilepwent_internal: closed password file.
[2009/04/20 12:51:10, 10] passdb/pdb_smbpasswd.c:smbpasswd_getsampwnam(1305)
  getsampwnam (smbpasswd): found by name: XXXXXXXXXX
[2009/04/20 12:51:10,  5] lib/username.c:Get_Pwnam_alloc(133)
  Finding user XXXXXXXXXX
[2009/04/20 12:51:10,  5] lib/username.c:Get_Pwnam_internals(77)
  Trying _Get_Pwnam(), username as lowercase is XXXXXXXXXX
[2009/04/20 12:51:10,  5] lib/username.c:Get_Pwnam_internals(110)
  Get_Pwnam_internals did find user [XXXXXXXXXX]!
[2009/04/20 12:51:10, 10] passdb/pdb_get_set.c:pdb_set_username(572)
  pdb_set_username: setting username XXXXXXXXXX, was 
[2009/04/20 12:51:10, 10] passdb/pdb_get_set.c:pdb_set_fullname(641)
  pdb_set_full_name: setting full name XXXXXXXXXX, was 
[2009/04/20 12:51:10, 10] passdb/pdb_get_set.c:pdb_set_domain(595)
  pdb_set_domain: setting domain XXXXXXXXXX, was 
[2009/04/20 12:51:10, 10] passdb/pdb_get_set.c:pdb_set_profile_path(687)
  pdb_set_profile_path: setting profile path \\XXXXXXXXXX\XXXXXXXXXX\XXXXXXXXXX, was 
[2009/04/20 12:51:10, 10] passdb/pdb_get_set.c:pdb_set_homedir(734)
  pdb_set_homedir: setting home dir \\XXXXXXXXXX\XXXXXXXXXX, was 
[2009/04/20 12:51:10, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(710)
  pdb_set_dir_drive: setting dir drive XX:, was NULL
[2009/04/20 12:51:10, 10] passdb/pdb_get_set.c:pdb_set_logon_script(664)
  pdb_set_logon_script: setting logon script , was 
[2009/04/20 12:51:10, 10] passdb/pdb_get_set.c:pdb_set_user_sid(501)
  pdb_set_user_sid: setting user sid XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
[2009/04/20 12:51:10, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72)
  pdb_set_user_sid_from_rid:
  	setting user sid XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX from rid XXXX
[2009/04/20 12:51:10,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2009/04/20 12:51:10,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
  pop_sec_ctx (XXXXXXXXXX, XXXXXXXXXX) - sec_ctx_stack_ndx = 0
[2009/04/20 12:51:10, 10] passdb/lookup_sid.c:legacy_uid_to_sid(1139)
  LEGACY: uid XXXXXXXXXX -> sid XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
[2009/04/20 12:51:10, 10] smbd/posix_acls.c:canonicalise_acl(2319)
  canonicalise_acl: Access ace entries before arrange :
[2009/04/20 12:51:10, 10] smbd/posix_acls.c:canonicalise_acl(2332)
  canon_ace index 0. Type = allow SID = XXXXXXXXXX other SMB_ACL_OTHER perms r--
[2009/04/20 12:51:10, 10] smbd/posix_acls.c:canonicalise_acl(2332)
  canon_ace index 1. Type = allow SID = XXXXXXXXXX gid XXXXXXXXXX (XXXXXXXXXX) SMB_ACL_GROUP_OBJ perms r--
[2009/04/20 12:51:10, 10] smbd/posix_acls.c:canonicalise_acl(2332)
  canon_ace index 2. Type = allow SID = XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX uid XXXXXXXXXX (XXXXXXXXXX) SMB_ACL_USER_OBJ perms rw-
[2009/04/20 12:51:10, 10] smbd/posix_acls.c:print_canon_ace_list(660)
  print_canon_ace_list: canonicalise_acl: ace entries after arrange
  canon_ace index 0. Type = allow SID = XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX uid XXXXXXXXXX (XXXXXXXXXX) SMB_ACL_USER_OBJ perms rw-
  canon_ace index 1. Type = allow SID = XXXXXXXXXX gid XXXXXXXXXX (XXXXXXXXXX) SMB_ACL_GROUP_OBJ perms r--
  canon_ace index 2. Type = allow SID = XXXXXXXXXX other SMB_ACL_OTHER perms r--
[2009/04/20 12:51:10, 10] smbd/posix_acls.c:map_canon_ace_perms(935)
  map_canon_ace_perms: Mapped (UNIX) 180 to (NT) 12019f
[2009/04/20 12:51:10, 10] smbd/posix_acls.c:map_canon_ace_perms(935)
  map_canon_ace_perms: Mapped (UNIX) 100 to (NT) 120089
[2009/04/20 12:51:10, 10] smbd/posix_acls.c:map_canon_ace_perms(935)
  map_canon_ace_perms: Mapped (UNIX) 100 to (NT) 120089
[2009/04/20 12:51:10, 10] smbd/open.c:check_open_rights(96)
  check_open_rights: file Sambatest/2GBtestfile.largetest requesting 0x180 returning 0x100 (NT_STATUS_OK)
[2009/04/20 12:51:10,  2] smbd/open.c:open_file(551)
  XXXXXXXXXX opened file Sambatest/2GBtestfile.largetest read=No write=No (numopen=1)
[2009/04/20 12:51:10,  5] smbd/oplock.c:set_file_oplock(126)
  set_file_oplock: granted oplock on file Sambatest/2GBtestfile.largetest, 811:4199ba/2, tv_sec = 49ecb60e, tv_usec = 6018
[2009/04/20 12:51:10, 10] locking/locking.c:unparse_share_modes(683)
  unparse_share_modes: del: 0, owrt: Mon 20 Apr 2009 12:51:09 PM CDT CDT cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num: 1
[2009/04/20 12:51:10, 10] locking/locking.c:print_share_mode_table(485)
  print_share_mode_table: share_mode_entry[0]:  pid = 12451, share_access = 0x7, private_options = 0x0, access_mask = 0x180, mid = 0x0, type= 0x0, gen_id = 2, uid = XXXXXXXXXX, flags = 0, file_id 811:4199ba
[2009/04/20 12:51:10, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
  Unlocking key 1108000000000000BA99
[2009/04/20 12:51:10, 10] smbd/open.c:create_file_unixpath(3209)
  create_file_unixpath: info=1
[2009/04/20 12:51:10, 10] smbd/open.c:create_file(3472)
  create_file: info=1
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode(361)
  dos_mode: Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
  dos_mode_from_sbuf returning 
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode(403)
  dos_mode returning 
[2009/04/20 12:51:10, 10] smbd/file_access.c:can_access_file_data(147)
  can_access_file_data: requesting 0x2 on file Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  5] smbd/nttrans.c:reply_ntcreate_and_X(607)
  reply_ntcreate_and_X: fnum = 11838, open name = Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=135
  smb_com=0xa2
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=45888
  smt_wct=42
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=15872 (0x3E00)
  smb_vwv[ 3]=  302 (0x12E)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=32768 (0x8000)
  smb_vwv[ 6]=64804 (0xFD24)
  smb_vwv[ 7]=57493 (0xE095)
  smb_vwv[ 8]=51649 (0xC9C1)
  smb_vwv[ 9]=32769 (0x8001)
  smb_vwv[10]=64804 (0xFD24)
  smb_vwv[11]=57493 (0xE095)
  smb_vwv[12]=51649 (0xC9C1)
  smb_vwv[13]=32769 (0x8001)
  smb_vwv[14]=64804 (0xFD24)
  smb_vwv[15]=57493 (0xE095)
  smb_vwv[16]=51649 (0xC9C1)
  smb_vwv[17]=32769 (0x8001)
  smb_vwv[18]=64804 (0xFD24)
  smb_vwv[19]=57493 (0xE095)
  smb_vwv[20]=51649 (0xC9C1)
  smb_vwv[21]=32769 (0x8001)
  smb_vwv[22]=    0 (0x0)
  smb_vwv[23]=    0 (0x0)
  smb_vwv[24]=    0 (0x0)
  smb_vwv[25]=    0 (0x0)
  smb_vwv[26]=    0 (0x0)
  smb_vwv[27]=    0 (0x0)
  smb_vwv[28]=    0 (0x0)
  smb_vwv[29]=    0 (0x0)
  smb_vwv[30]=    0 (0x0)
  smb_vwv[31]=    0 (0x0)
  smb_vwv[32]= 1792 (0x700)
  smb_vwv[33]=    0 (0x0)
  smb_vwv[34]=    0 (0x0)
  smb_vwv[35]=    0 (0x0)
  smb_vwv[36]=    0 (0x0)
  smb_vwv[37]=    0 (0x0)
  smb_vwv[38]=    0 (0x0)
  smb_vwv[39]=    0 (0x0)
  smb_vwv[40]=    0 (0x0)
  smb_vwv[41]=    0 (0x0)
  smb_bcc=0
[2009/04/20 12:51:10, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
  got smb length of 72
[2009/04/20 12:51:10,  6] smbd/process.c:process_smb(1551)
  got message type 0x0 of len 0x48
[2009/04/20 12:51:10,  3] smbd/process.c:process_smb(1554)
  Transaction 9 of length 76 (0 toread)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=72
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=45952
  smt_wct=15
  smb_vwv[ 0]=    4 (0x4)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]=   40 (0x28)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    4 (0x4)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    7 (0x7)
  smb_bcc=7
[2009/04/20 12:51:10, 10] lib/util.c:dump_data(2233)
  [000] 00 00 00 3E 2E EC 03                              ...>... 
[2009/04/20 12:51:10,  3] smbd/process.c:switch_message(1378)
  switch message SMBtrans2 (pid 12451) conn 0x86e1cb8
[2009/04/20 12:51:10,  4] smbd/uid.c:change_to_user(213)
  change_to_user: Skipping user change - already user
[2009/04/20 12:51:10,  3] smbd/trans2.c:call_trans2qfilepathinfo(3867)
  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1004
[2009/04/20 12:51:10, 10] locking/locking.c:parse_share_modes(519)
  parse_share_modes: delete_on_close: 0, owrt: Mon 20 Apr 2009 12:51:09 PM CDT CDT, cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num_share_modes: 1
[2009/04/20 12:51:10, 10] locking/locking.c:parse_share_modes(616)
  parse_share_modes: share_mode_entry[0]:  pid = 12451, share_access = 0x7, private_options = 0x0, access_mask = 0x180, mid = 0x0, type= 0x0, gen_id = 2, uid = XXXXXXXXXX, flags = 0, file_id 811:4199ba
[2009/04/20 12:51:10,  3] smbd/trans2.c:call_trans2qfilepathinfo(4057)
  call_trans2qfilepathinfo Sambatest/2GBtestfile.largetest (fnum = 11838) level=1004 call=7 total_data=0
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode(361)
  dos_mode: Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
  dos_mode_from_sbuf returning 
[2009/04/20 12:51:10,  8] smbd/dosmode.c:dos_mode(403)
  dos_mode returning 
[2009/04/20 12:51:10, 10] smbd/trans2.c:call_trans2qfilepathinfo(4299)
  call_trans2qfilepathinfo: SMB_FILE_BASIC_INFORMATION
[2009/04/20 12:51:10,  5] smbd/trans2.c:call_trans2qfilepathinfo(4309)
  SMB_QFBI - create: Mon Apr 20 12:51:09 2009
   access: Mon Apr 20 12:51:09 2009
   write: Mon Apr 20 12:51:09 2009
   change: Mon Apr 20 12:51:09 2009
   mode: 80
[2009/04/20 12:51:10,  9] smbd/trans2.c:send_trans2_replies(840)
  t2_rep: params_sent_thistime = 2, data_sent_thistime = 40, useable_space = 131010
[2009/04/20 12:51:10,  9] smbd/trans2.c:send_trans2_replies(842)
  t2_rep: params_to_send = 2, data_to_send = 40, paramsize = 2, datasize = 40
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=100
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=45952
  smt_wct=10
  smb_vwv[ 0]=    2 (0x2)
  smb_vwv[ 1]=   40 (0x28)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    2 (0x2)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=   40 (0x28)
  smb_vwv[ 7]=   60 (0x3C)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=45
[2009/04/20 12:51:10, 10] lib/util.c:dump_data(2233)
  [000] 00 00 00 00 00 80 24 FD  95 E0 C1 C9 01 80 24 FD  ......$. ......$.
  [010] 95 E0 C1 C9 01 80 24 FD  95 E0 C1 C9 01 80 24 FD  ......$. ......$.
  [020] 95 E0 C1 C9 01 80 00 00  00 00 00 00 00           ........ .....
[2009/04/20 12:51:10, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
  got smb length of 41
[2009/04/20 12:51:10,  6] smbd/process.c:process_smb(1551)
  got message type 0x0 of len 0x29
[2009/04/20 12:51:10,  3] smbd/process.c:process_smb(1554)
  Transaction 10 of length 45 (0 toread)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=41
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=65279
  smb_uid=XXXXXXXXXX
  smb_mid=46016
  smt_wct=3
  smb_vwv[ 0]=11838 (0x2E3E)
  smb_vwv[ 1]=65535 (0xFFFF)
  smb_vwv[ 2]=65535 (0xFFFF)
  smb_bcc=0
[2009/04/20 12:51:10,  3] smbd/process.c:switch_message(1378)
  switch message SMBclose (pid 12451) conn 0x86e1cb8
[2009/04/20 12:51:10,  4] smbd/uid.c:change_to_user(213)
  change_to_user: Skipping user change - already user
[2009/04/20 12:51:10,  3] smbd/reply.c:reply_close(4353)
  close fd=-1 fnum=11838 (numopen=1)
[2009/04/20 12:51:10,  6] smbd/close.c:set_close_write_time(450)
  close_write_time: Wed Dec 31 17:59:59 1969
[2009/04/20 12:51:10, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
  Locking key 1108000000000000BA99
[2009/04/20 12:51:10, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
  Allocated locked data 0x0x86f0498
[2009/04/20 12:51:10, 10] locking/locking.c:parse_share_modes(519)
  parse_share_modes: delete_on_close: 0, owrt: Mon 20 Apr 2009 12:51:09 PM CDT CDT, cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num_share_modes: 1
[2009/04/20 12:51:10, 10] locking/locking.c:parse_share_modes(616)
  parse_share_modes: share_mode_entry[0]:  pid = 12451, share_access = 0x7, private_options = 0x0, access_mask = 0x180, mid = 0x0, type= 0x0, gen_id = 2, uid = XXXXXXXXXX, flags = 0, file_id 811:4199ba
[2009/04/20 12:51:10, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
  Unlocking key 1108000000000000BA99
[2009/04/20 12:51:10,  2] smbd/close.c:close_normal_file(606)
  XXXXXXXXXX closed file Sambatest/2GBtestfile.largetest (numopen=0) NT_STATUS_OK
[2009/04/20 12:51:10,  5] smbd/files.c:file_free(471)
  freed files structure 11838 (0 used)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:10,  5] lib/util.c:show_msg(655)
  size=35
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=65279
  smb_uid=XXXXXXXXXX
  smb_mid=46016
  smt_wct=0
  smb_bcc=0
[2009/04/20 12:51:11, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
  got smb length of 96
[2009/04/20 12:51:11,  6] smbd/process.c:process_smb(1551)
  got message type 0x0 of len 0x60
[2009/04/20 12:51:11,  3] smbd/process.c:process_smb(1554)
  Transaction 11 of length 100 (0 toread)
[2009/04/20 12:51:11,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:11,  5] lib/util.c:show_msg(655)
  size=96
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=46080
  smt_wct=15
  smb_vwv[ 0]=   28 (0x1C)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]=   40 (0x28)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=   28 (0x1C)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    5 (0x5)
  smb_bcc=31
[2009/04/20 12:51:11, 10] lib/util.c:dump_data(2233)
  [000] 00 00 00 EC 03 00 00 00  00 5C 00 53 00 61 00 6D  ........ .\.S.a.m
  [010] 00 62 00 61 00 74 00 65  00 73 00 74 00 00 00     .b.a.t.e .s.t...
[2009/04/20 12:51:11,  3] smbd/process.c:switch_message(1378)
  switch message SMBtrans2 (pid 12451) conn 0x86e1cb8
[2009/04/20 12:51:11,  4] smbd/uid.c:change_to_user(213)
  change_to_user: Skipping user change - already user
[2009/04/20 12:51:11,  3] smbd/trans2.c:call_trans2qfilepathinfo(3943)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2009/04/20 12:51:11,  5] smbd/filename.c:unix_convert(148)
  unix_convert called on file "Sambatest"
[2009/04/20 12:51:11, 10] smbd/statcache.c:stat_cache_lookup(235)
  stat_cache_lookup: lookup failed for name [SAMBATEST]
[2009/04/20 12:51:11,  5] smbd/statcache.c:stat_cache_add(138)
  stat_cache_add: Added entry (86f04e8:size 9) SAMBATEST -> Sambatest
[2009/04/20 12:51:11,  5] smbd/filename.c:unix_convert(297)
  conversion finished Sambatest -> Sambatest
[2009/04/20 12:51:11, 10] locking/locking.c:parse_share_modes(519)
  parse_share_modes: delete_on_close: 0, owrt: Mon 20 Apr 2009 12:49:22 PM CDT CDT, cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num_share_modes: 2
[2009/04/20 12:51:11, 10] locking/locking.c:parse_share_modes(616)
  parse_share_modes: share_mode_entry[0]: UNUSED pid = 12352, share_access = 0x7, private_options = 0x0, access_mask = 0x20089, mid = 0x0, type= 0x40, gen_id = 113, uid = XXXXXXXXXX, flags = 0, file_id 811:419961
[2009/04/20 12:51:11, 10] locking/locking.c:parse_share_modes(619)
  parse_share_modes: deleted share_mode_entry[0]: UNUSED pid = 12352, share_access = 0x7, private_options = 0x0, access_mask = 0x20089, mid = 0x0, type= 0x40, gen_id = 113, uid = XXXXXXXXXX, flags = 0, file_id 811:419961
[2009/04/20 12:51:11, 10] locking/locking.c:parse_share_modes(616)
  parse_share_modes: share_mode_entry[1]:  pid = 12352, share_access = 0x7, private_options = 0x1, access_mask = 0x100081, mid = 0x0, type= 0x0, gen_id = 60, uid = XXXXXXXXXX, flags = 0, file_id 811:419961
[2009/04/20 12:51:11, 10] locking/locking.c:parse_share_modes(619)
  parse_share_modes: deleted share_mode_entry[1]:  pid = 12352, share_access = 0x7, private_options = 0x1, access_mask = 0x100081, mid = 0x0, type= 0x0, gen_id = 60, uid = XXXXXXXXXX, flags = 0, file_id 811:419961
[2009/04/20 12:51:11,  3] smbd/trans2.c:call_trans2qfilepathinfo(4057)
  call_trans2qfilepathinfo Sambatest (fnum = -1) level=1004 call=5 total_data=0
[2009/04/20 12:51:11,  8] smbd/dosmode.c:dos_mode(361)
  dos_mode: Sambatest
[2009/04/20 12:51:11,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
  dos_mode_from_sbuf returning d
[2009/04/20 12:51:11,  8] smbd/dosmode.c:dos_mode(403)
  dos_mode returning d
[2009/04/20 12:51:11, 10] smbd/trans2.c:call_trans2qfilepathinfo(4299)
  call_trans2qfilepathinfo: SMB_FILE_BASIC_INFORMATION
[2009/04/20 12:51:11,  5] smbd/trans2.c:call_trans2qfilepathinfo(4309)
  SMB_QFBI - create: Mon Apr 20 12:51:09 2009
   access: Mon Apr 20 12:51:09 2009
   write: Mon Apr 20 12:49:22 2009
   change: Mon Apr 20 12:49:22 2009
   mode: 10
[2009/04/20 12:51:11,  9] smbd/trans2.c:send_trans2_replies(840)
  t2_rep: params_sent_thistime = 2, data_sent_thistime = 40, useable_space = 131010
[2009/04/20 12:51:11,  9] smbd/trans2.c:send_trans2_replies(842)
  t2_rep: params_to_send = 2, data_to_send = 40, paramsize = 2, datasize = 40
[2009/04/20 12:51:11,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:11,  5] lib/util.c:show_msg(655)
  size=100
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=46080
  smt_wct=10
  smb_vwv[ 0]=    2 (0x2)
  smb_vwv[ 1]=   40 (0x28)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    2 (0x2)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=   40 (0x28)
  smb_vwv[ 7]=   60 (0x3C)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=45
[2009/04/20 12:51:11, 10] lib/util.c:dump_data(2233)
  [000] 00 00 00 00 00 80 24 FD  95 E0 C1 C9 01 80 24 FD  ......$. ......$.
  [010] 95 E0 C1 C9 01 00 3D 36  56 E0 C1 C9 01 00 3D 36  ......=6 V.....=6
  [020] 56 E0 C1 C9 01 10 00 00  00 00 00 00 00           V....... .....
[2009/04/20 12:51:11, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
  got smb length of 106
[2009/04/20 12:51:11,  6] smbd/process.c:process_smb(1551)
  got message type 0x0 of len 0x6a
[2009/04/20 12:51:11,  3] smbd/process.c:process_smb(1554)
  Transaction 12 of length 110 (0 toread)
[2009/04/20 12:51:11,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:11,  5] lib/util.c:show_msg(655)
  size=106
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=46144
  smt_wct=15
  smb_vwv[ 0]=   38 (0x26)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=   10 (0xA)
  smb_vwv[ 3]=16384 (0x4000)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=   38 (0x26)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    1 (0x1)
  smb_bcc=41
[2009/04/20 12:51:11, 10] lib/util.c:dump_data(2233)
  [000] 00 00 00 16 00 56 05 06  00 04 01 00 00 00 00 5C  .....V.. .......\
  [010] 00 53 00 61 00 6D 00 62  00 61 00 74 00 65 00 73  .S.a.m.b .a.t.e.s
  [020] 00 74 00 5C 00 2A 00 00  00                       .t.\.*.. .
[2009/04/20 12:51:11,  3] smbd/process.c:switch_message(1378)
  switch message SMBtrans2 (pid 12451) conn 0x86e1cb8
[2009/04/20 12:51:11,  4] smbd/uid.c:change_to_user(213)
  change_to_user: Skipping user change - already user
[2009/04/20 12:51:11,  3] smbd/trans2.c:call_trans2findfirst(1926)
  call_trans2findfirst: dirtype = 16, maxentries = 1366, close_after_first=0, close_if_end = 1 requires_resume_key = 1 level = 0x104, max_data_bytes = 16384
[2009/04/20 12:51:11,  5] smbd/filename.c:unix_convert(148)
  unix_convert called on file "Sambatest/*"
[2009/04/20 12:51:11, 10] smbd/statcache.c:stat_cache_lookup(235)
  stat_cache_lookup: lookup failed for name [SAMBATEST/*]
[2009/04/20 12:51:11, 10] smbd/statcache.c:stat_cache_lookup(274)
  stat_cache_lookup: lookup succeeded for name [SAMBATEST] -> [Sambatest]
[2009/04/20 12:51:11,  5] smbd/filename.c:unix_convert(303)
  unix_convert begin: name = Sambatest/*, dirpath = Sambatest, start = *
[2009/04/20 12:51:11, 10] smbd/mangle_hash2.c:is_mangled(249)
  is_mangled * ?
[2009/04/20 12:51:11, 10] smbd/mangle_hash2.c:is_mangled_component(188)
  is_mangled_component * (len 1) ?
[2009/04/20 12:51:11, 10] smbd/mangle_hash2.c:is_mangled(249)
  is_mangled * ?
[2009/04/20 12:51:11, 10] smbd/mangle_hash2.c:is_mangled_component(188)
  is_mangled_component * (len 1) ?
[2009/04/20 12:51:11,  5] smbd/filename.c:unix_convert(559)
  New file *
[2009/04/20 12:51:11,  5] smbd/trans2.c:call_trans2findfirst(2013)
  dir=Sambatest, mask = *
[2009/04/20 12:51:11,  5] smbd/dir.c:dptr_create(400)
  dptr_create dir=Sambatest
[2009/04/20 12:51:11,  3] smbd/dir.c:dptr_create(518)
  creating new dirptr 256 for path Sambatest, expect_close = 1
[2009/04/20 12:51:11,  4] smbd/trans2.c:call_trans2findfirst(2080)
  dptr_num is 256, wcard = *, attr = 22
[2009/04/20 12:51:11,  8] smbd/trans2.c:call_trans2findfirst(2085)
  dirpath=<Sambatest> dontdescend=<>
[2009/04/20 12:51:11,  8] smbd/trans2.c:get_lanman2_dir_entry(1317)
  get_lanman2_dir_entry:readdir on dirptr 0x86eb330 now at offset 0
[2009/04/20 12:51:11,  8] smbd/dosmode.c:dos_mode(361)
  dos_mode: Sambatest/.
[2009/04/20 12:51:11,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
  dos_mode_from_sbuf returning d
[2009/04/20 12:51:11,  8] smbd/dosmode.c:dos_mode(403)
  dos_mode returning d
[2009/04/20 12:51:11, 10] locking/locking.c:parse_share_modes(519)
  parse_share_modes: delete_on_close: 0, owrt: Mon 20 Apr 2009 12:49:22 PM CDT CDT, cwrt: Wed 31 Dec 1969 06:00:00 PM CST CST, tok: 0, num_share_modes: 2
[2009/04/20 12:51:11, 10] locking/locking.c:parse_share_modes(616)
  parse_share_modes: share_mode_entry[0]: UNUSED pid = 12352, share_access = 0x7, private_options = 0x0, access_mask = 0x20089, mid = 0x0, type= 0x40, gen_id = 113, uid = XXXXXXXXXX, flags = 0, file_id 811:419961
[2009/04/20 12:51:11, 10] locking/locking.c:parse_share_modes(619)
  parse_share_modes: deleted share_mode_entry[0]: UNUSED pid = 12352, share_access = 0x7, private_options = 0x0, access_mask = 0x20089, mid = 0x0, type= 0x40, gen_id = 113, uid = XXXXXXXXXX, flags = 0, file_id 811:419961
[2009/04/20 12:51:11, 10] locking/locking.c:parse_share_modes(616)
  parse_share_modes: share_mode_entry[1]:  pid = 12352, share_access = 0x7, private_options = 0x1, access_mask = 0x100081, mid = 0x0, type= 0x0, gen_id = 60, uid = XXXXXXXXXX, flags = 0, file_id 811:419961
[2009/04/20 12:51:11, 10] locking/locking.c:parse_share_modes(619)
  parse_share_modes: deleted share_mode_entry[1]:  pid = 12352, share_access = 0x7, private_options = 0x1, access_mask = 0x100081, mid = 0x0, type= 0x0, gen_id = 60, uid = XXXXXXXXXX, flags = 0, file_id 811:419961
[2009/04/20 12:51:11,  5] smbd/trans2.c:get_lanman2_dir_entry(1448)
  get_lanman2_dir_entry: found Sambatest/. fname=.
[2009/04/20 12:51:11, 10] smbd/trans2.c:get_lanman2_dir_entry(1597)
  get_lanman2_dir_entry: SMB_FIND_FILE_BOTH_DIRECTORY_INFO
[2009/04/20 12:51:11,  8] smbd/trans2.c:get_lanman2_dir_entry(1317)
  get_lanman2_dir_entry:readdir on dirptr 0x86eb330 now at offset -2147483648
[2009/04/20 12:51:11,  8] smbd/dosmode.c:dos_mode(361)
  dos_mode: Sambatest/..
[2009/04/20 12:51:11,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
  dos_mode_from_sbuf returning d
[2009/04/20 12:51:11,  8] smbd/dosmode.c:dos_mode(403)
  dos_mode returning d
[2009/04/20 12:51:11, 10] locking/locking.c:fetch_share_mode_unlocked(858)
  fetch_share_mode_unlocked: no share_mode record around (file not open)
[2009/04/20 12:51:11,  5] smbd/trans2.c:get_lanman2_dir_entry(1448)
  get_lanman2_dir_entry: found Sambatest/.. fname=..
[2009/04/20 12:51:11, 10] smbd/trans2.c:get_lanman2_dir_entry(1597)
  get_lanman2_dir_entry: SMB_FIND_FILE_BOTH_DIRECTORY_INFO
[2009/04/20 12:51:11,  8] smbd/trans2.c:get_lanman2_dir_entry(1317)
  get_lanman2_dir_entry:readdir on dirptr 0x86eb330 now at offset 1739018241
[2009/04/20 12:51:11,  8] smbd/dosmode.c:dos_mode(361)
  dos_mode: Sambatest/2GBtestfile.largetest
[2009/04/20 12:51:11,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
  dos_mode_from_sbuf returning 
[2009/04/20 12:51:11,  8] smbd/dosmode.c:dos_mode(403)
  dos_mode returning 
[2009/04/20 12:51:11, 10] locking/locking.c:fetch_share_mode_unlocked(858)
  fetch_share_mode_unlocked: no share_mode record around (file not open)
[2009/04/20 12:51:11,  5] smbd/trans2.c:get_lanman2_dir_entry(1448)
  get_lanman2_dir_entry: found Sambatest/2GBtestfile.largetest fname=2GBtestfile.largetest
[2009/04/20 12:51:11, 10] smbd/trans2.c:get_lanman2_dir_entry(1597)
  get_lanman2_dir_entry: SMB_FIND_FILE_BOTH_DIRECTORY_INFO
[2009/04/20 12:51:11, 10] smbd/mangle_hash2.c:hash2_name_to_8_3(609)
  hash2_name_to_8_3: 2GBtestfile.largetest -> 74AFD4AF -> 2WDJVS~F (cache=1)
[2009/04/20 12:51:11,  8] smbd/trans2.c:get_lanman2_dir_entry(1317)
  get_lanman2_dir_entry:readdir on dirptr 0x86eb330 now at offset -1
[2009/04/20 12:51:11,  5] smbd/trans2.c:call_trans2findfirst(2141)
  call_trans2findfirst - (2) closing dptr_num 256
[2009/04/20 12:51:11,  4] smbd/dir.c:dptr_close_internal(249)
  closing dptr key 256
[2009/04/20 12:51:11,  9] smbd/trans2.c:send_trans2_replies(840)
  t2_rep: params_sent_thistime = 10, data_sent_thistime = 332, useable_space = 131010
[2009/04/20 12:51:11,  9] smbd/trans2.c:send_trans2_replies(842)
  t2_rep: params_to_send = 10, data_to_send = 332, paramsize = 10, datasize = 332
[2009/04/20 12:51:11,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:11,  5] lib/util.c:show_msg(655)
  size=400
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=46144
  smt_wct=10
  smb_vwv[ 0]=   10 (0xA)
  smb_vwv[ 1]=  332 (0x14C)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=   10 (0xA)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=  332 (0x14C)
  smb_vwv[ 7]=   68 (0x44)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=345
[2009/04/20 12:51:11, 10] lib/util.c:dump_data(2233)
  [000] 00 FD FF 03 00 01 00 00  00 C4 00 00 00 60 00 00  ........ .....`..
  [010] 00 00 00 00 00 80 24 FD  95 E0 C1 C9 01 80 24 FD  ......$. ......$.
  [020] 95 E0 C1 C9 01 00 3D 36  56 E0 C1 C9 01 00 3D 36  ......=6 V.....=6
  [030] 56 E0 C1 C9 01 00 00 00  00 00 00 00 00 00 00 00  V....... ........
  [040] 00 00 00 00 00 10 00 00  00 02 00 00 00 00 00 00  ........ ........
  [050] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
  [060] 00 00 00 00 00 00 00 00  00 00 00 2E 00 64 00 00  ........ .....d..
  [070] 00 00 00 00 00 00 EE 3E  6F E0 C1 C9 01 00 EE 3E  .......> o......>
  [080] 6F E0 C1 C9 01 80 84 D7  6F E0 C1 C9 01 80 84 D7  o....... o.......
  [090] 6F E0 C1 C9 01 00 00 00  00 00 00 00 00 00 00 00  o....... ........
  [0A0] 00 00 00 00 00 10 00 00  00 04 00 00 00 00 00 00  ........ ........
  [0B0] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
  [0C0] 00 00 00 00 00 00 00 00  00 00 00 2E 00 2E 00 00  ........ ........
  [0D0] 00 88 00 00 00 00 00 00  00 80 24 FD 95 E0 C1 C9  ........ ..$.....
  [0E0] 01 80 24 FD 95 E0 C1 C9  01 80 24 FD 95 E0 C1 C9  ..$..... ..$.....
  [0F0] 01 80 24 FD 95 E0 C1 C9  01 00 00 00 00 00 00 00  ..$..... ........
  [100] 00 00 00 00 00 00 00 00  00 80 00 00 00 2A 00 00  ........ .....*..
  [110] 00 00 00 00 00 10 00 32  00 57 00 44 00 4A 00 56  .......2 .W.D.J.V
  [120] 00 53 00 7E 00 46 00 00  00 00 00 00 00 00 00 32  .S.~.F.. .......2
  [130] 00 47 00 42 00 74 00 65  00 73 00 74 00 66 00 69  .G.B.t.e .s.t.f.i
  [140] 00 6C 00 65 00 2E 00 6C  00 61 00 72 00 67 00 65  .l.e...l .a.r.g.e
  [150] 00 74 00 65 00 73 00 74  00                       .t.e.s.t .
[2009/04/20 12:51:11,  4] smbd/trans2.c:call_trans2findfirst(2185)
  SMBtrans2 mask=* directory=Sambatest dirtype=22 numentries=3
[2009/04/20 12:51:11, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
  got smb length of 96
[2009/04/20 12:51:11,  6] smbd/process.c:process_smb(1551)
  got message type 0x0 of len 0x60
[2009/04/20 12:51:11,  3] smbd/process.c:process_smb(1554)
  Transaction 13 of length 100 (0 toread)
[2009/04/20 12:51:11,  5] lib/util.c:show_msg(645)
[2009/04/20 12:51:11,  5] lib/util.c:show_msg(655)
  size=96
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=456
  smb_uid=XXXXXXXXXX
  smb_mid=46208
  smt_wct=15
  smb_vwv[ 0]=   28 (0x1C)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]=   40 (0x28)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=   28 (0x1C)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    5 (0x5)
  smb_bcc=31


More information about the samba mailing list