[2005/10/05 10:04:24, 10] lib/util_sock.c:read_smb_length_return_keepalive(615)
  got smb length of 90
[2005/10/05 10:04:24, 6] smbd/process.c:process_smb(1113)
  got message type 0x0 of len 0x5a
[2005/10/05 10:04:24, 3] smbd/process.c:process_smb(1114)
  Transaction 25 of length 94
[2005/10/05 10:04:24, 5] lib/util.c:show_msg(454)
[2005/10/05 10:04:24, 5] lib/util.c:show_msg(464)
  size=90
  smb_com=0x2d
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=0
  smb_flg2=32768
  smb_tid=1
  smb_pid=5311
  smb_uid=100
  smb_mid=45441
  smt_wct=15
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    7 (0x7)
  smb_vwv[ 3]=   34 (0x22)
  smb_vwv[ 4]=   22 (0x16)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=45880 (0xB338)
  smb_vwv[ 7]=17219 (0x4343)
  smb_vwv[ 8]=   18 (0x12)
  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_bcc=25
[2005/10/05 10:04:24, 10] lib/util.c:dump_data(2053)
  [000] 00 5C 00 31 00 5C 00 74  00 65 00 73 00 74 00 2E  .\.1.\.t .e.s.t..
  [010] 00 74 00 78 00 74 00 00  00                       .t.x.t.. .
[2005/10/05 10:04:24, 3] smbd/process.c:switch_message(900)
  switch message SMBopenX (pid 92057) conn 0x8357828
[2005/10/05 10:04:24, 4] smbd/uid.c:change_to_user(217)
  change_to_user: Skipping user change - already user
[2005/10/05 10:04:25, 5] smbd/filename.c:unix_convert(108)
  unix_convert called on file "1/test.txt"
[2005/10/05 10:04:25, 10] smbd/statcache.c:stat_cache_lookup(215)
  stat_cache_lookup: lookup failed for name [1/TEST.TXT]
[2005/10/05 10:04:25, 10] smbd/statcache.c:stat_cache_lookup(215)
  stat_cache_lookup: lookup failed for name [1]
[2005/10/05 10:04:25, 5] smbd/statcache.c:stat_cache_add(140)
  stat_cache_add: Added entry (832f180:sizeb) 1/TEST.TXT -> 1/test.txt
[2005/10/05 10:04:25, 5] smbd/filename.c:unix_convert(170)
  conversion finished 1/test.txt -> 1/test.txt
[2005/10/05 10:04:25, 10] smbd/open.c:map_open_params_to_ntcreate(1045)
  map_open_params_to_ntcreate: fname = 1/test.txt, deny_mode = 0x22, open_func = 0x12
[2005/10/05 10:04:25, 10] smbd/open.c:map_open_params_to_ntcreate(1148)
  map_open_params_to_ntcreate: file 1/test.txt, access_mask = 0x12019f, share_mode = 0x1, create_disposition = 0x5, create_options = 0x0
[2005/10/05 10:04:25, 3] smbd/dosmode.c:unix_mode(121)
  unix_mode(1/test.txt) returning 0744
[2005/10/05 10:04:25, 10] smbd/open.c:open_file_ntcreate(1236)
  open_file_ntcreate: fname=1/test.txt, dos_attrs=0x0 access_mask=0x12019f share_access=0x1 create_disposition = 0x5 create_options=0x0 unix mode=0744 oplock_request=3
[2005/10/05 10:04:25, 8] smbd/dosmode.c:dos_mode(294)
  dos_mode: 1/test.txt
[2005/10/05 10:04:25, 10] smbd/dosmode.c:get_ea_dos_attribute(203)
  get_ea_dos_attribute: 1/test.txt attrstr = 0x20
[2005/10/05 10:04:25, 8] smbd/dosmode.c:get_ea_dos_attribute(216)
  get_ea_dos_attribute returning (0x20)a
[2005/10/05 10:04:25, 8] smbd/dosmode.c:dos_mode(328)
  dos_mode returning a
[2005/10/05 10:04:25, 10] smbd/open.c:open_match_attributes(949)
  open_match_attributes: file 1/test.txt old_dos_attr = 0x20, existing_unx_mode = 0100644, new_dos_attr = 0x0 returned_unx_mode = 00
[2005/10/05 10:04:25, 10] smbd/open.c:open_file_ntcreate(1409)
  open_file_ntcreate: fname=1/test.txt, after mapping access_mask=0x12019f
[2005/10/05 10:04:25, 0] smbd/open.c:open_file_ntcreate(1457)
  open_file_ntcreate: file 1/test.txt flags = 02, file_existed = 1, can_write = 1 			attributes = 0x20
[2005/10/05 10:04:25, 5] smbd/files.c:file_new(139)
  allocated file structure 92, fnum = 4188 (1 used)
[2005/10/05 10:04:25, 4] smbd/open.c:open_file_ntcreate(1584)
  calling open_file with flags=0x2 flags2=0x600 mode=0744
[2005/10/05 10:04:25, 10] smbd/open.c:fd_open(55)
  fd_open: name 1/test.txt, flags = 01002 mode = 0744, fd = -1. Permission denied
[2005/10/05 10:04:25, 3] smbd/open.c:open_file(296)
  Error opening file 1/test.txt (Permission denied) (local_flags=514) (flags=1538)
[2005/10/05 10:04:25, 10] smbd/open.c:fd_open(55)
  fd_open: name 1/test.txt, flags = 00 mode = 0744, fd = 28. 
[2005/10/05 10:04:25, 2] smbd/open.c:open_file(372)
  RC20 opened file 1/test.txt read=Yes write=Yes (numopen=1)
[2005/10/05 10:04:25, 10] locking/locking.c:set_share_mode(753)
  set_share_mode: creating entry for file 1/test.txt. num_share_modes = 1
[2005/10/05 10:04:25, 10] locking/locking.c:print_share_mode_table(463)
  print_share_mode_table: share_mode_entry[0]: pid = 92057, share_access = 0x1, private_options = 0x0, access_mask = 0x12019f, port = 0x0, type= 0x0, file_id = 1, dev = 0x415, inode = 779539
[2005/10/05 10:04:25, 10] smbd/dosmode.c:file_set_dosmode(353)
  file_set_dosmode: setting dos mode 0x20 on file 1/test.txt
[2005/10/05 10:04:25, 8] smbd/dosmode.c:dos_mode(294)
  dos_mode: 1/test.txt
[2005/10/05 10:04:25, 10] smbd/dosmode.c:get_ea_dos_attribute(203)
  get_ea_dos_attribute: 1/test.txt attrstr = 0x20
[2005/10/05 10:04:25, 8] smbd/dosmode.c:get_ea_dos_attribute(216)
  get_ea_dos_attribute returning (0x20)a
[2005/10/05 10:04:25, 8] smbd/dosmode.c:dos_mode(328)
  dos_mode returning a
[2005/10/05 10:04:25, 8] smbd/dosmode.c:dos_mode(294)
  dos_mode: 1/test.txt
[2005/10/05 10:04:25, 10] smbd/dosmode.c:get_ea_dos_attribute(203)
  get_ea_dos_attribute: 1/test.txt attrstr = 0x20
[2005/10/05 10:04:25, 8] smbd/dosmode.c:get_ea_dos_attribute(216)
  get_ea_dos_attribute returning (0x20)a
[2005/10/05 10:04:25, 8] smbd/dosmode.c:dos_mode(328)
  dos_mode returning a
[2005/10/05 10:04:25, 5] lib/util.c:show_msg(454)
[2005/10/05 10:04:25, 5] lib/util.c:show_msg(464)
  size=65
  smb_com=0x2d
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=128
  smb_flg2=32769
  smb_tid=1
  smb_pid=5311
  smb_uid=100
  smb_mid=45441
  smt_wct=15
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]= 4188 (0x105C)
  smb_vwv[ 3]=   32 (0x20)
  smb_vwv[ 4]=18855 (0x49A7)
  smb_vwv[ 5]=17218 (0x4342)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    2 (0x2)
  smb_vwv[ 9]=    0 (0x0)
  smb_vwv[10]=    0 (0x0)
  smb_vwv[11]=    3 (0x3)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    0 (0x0)
  smb_vwv[14]=    0 (0x0)
  smb_bcc=0
[2005/10/05 10:04:25, 10] lib/util_sock.c:read_smb_length_return_keepalive(615)
  got smb length of 54
[2005/10/05 10:04:25, 6] smbd/process.c:process_smb(1113)
  got message type 0x0 of len 0x36
[2005/10/05 10:04:25, 3] smbd/process.c:process_smb(1114)
  Transaction 26 of length 58
[2005/10/05 10:04:25, 5] lib/util.c:show_msg(454)
[2005/10/05 10:04:25, 5] lib/util.c:show_msg(464)
  size=54
  smb_com=0xb
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=0
  smb_flg2=32768
  smb_tid=1
  smb_pid=5311
  smb_uid=100
  smb_mid=45569
  smt_wct=5
  smb_vwv[ 0]= 4188 (0x105C)
  smb_vwv[ 1]=    6 (0x6)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    0 (0x0)
  smb_vwv[ 4]=    6 (0x6)
  smb_bcc=9
[2005/10/05 10:04:25, 10] lib/util.c:dump_data(2053)
  [000] 01 06 00 78 78 78 20 0D  0A                       ...xxx . .
[2005/10/05 10:04:25, 3] smbd/process.c:switch_message(900)
  switch message SMBwrite (pid 92057) conn 0x8357828
[2005/10/05 10:04:25, 4] smbd/uid.c:change_to_user(217)
  change_to_user: Skipping user change - already user
[2005/10/05 10:04:25, 10] locking/locking.c:is_locked(109)
  is_locked: brl start=0 len=6 unlocked for file 1/test.txt
[2005/10/05 10:04:25, 10] locking/posix.c:is_posix_locked(706)
  is_posix_locked: File 1/test.txt, offset = 0, count = 6, type = READ
[2005/10/05 10:04:25, 10] locking/posix.c:posix_lock_in_range(642)
  posix_lock_in_range: offset_out = 0, count_out = 6
[2005/10/05 10:04:25, 8] locking/posix.c:posix_fcntl_lock(659)
  posix_fcntl_lock 28 7 0 6 3
[2005/10/05 10:04:25, 8] lib/util.c:fcntl_lock(1815)
  fcntl_lock 28 7 0 6 3
[2005/10/05 10:04:25, 8] locking/posix.c:posix_fcntl_lock(689)
  posix_fcntl_lock: Lock call failed
[2005/10/05 10:04:25, 10] locking/locking.c:is_locked(121)
  is_locked: posix start=0 len=6 unlocked for file 1/test.txt
[2005/10/05 10:04:25, 8] smbd/dosmode.c:dos_mode(294)
  dos_mode: 1/test.txt
[2005/10/05 10:04:25, 10] smbd/dosmode.c:get_ea_dos_attribute(203)
  get_ea_dos_attribute: 1/test.txt attrstr = 0x20
[2005/10/05 10:04:25, 8] smbd/dosmode.c:get_ea_dos_attribute(216)
  get_ea_dos_attribute returning (0x20)a
[2005/10/05 10:04:25, 8] smbd/dosmode.c:dos_mode(328)
  dos_mode returning a
[2005/10/05 10:04:25, 10] smbd/fileio.c:real_write_file(137)
  real_write_file (1/test.txt): pos = 0, size = 6, returned -1
[2005/10/05 10:04:25, 3] smbd/error.c:unix_error_packet(91)
  unix_error_packet: error string = Bad file descriptor
[2005/10/05 10:04:25, 3] smbd/error.c:error_packet(163)
  error packet at smbd/reply.c(2948) cmd=11 (SMBwrite) eclass=2 ecode=65
[2005/10/05 10:04:25, 5] lib/util.c:show_msg(454)
[2005/10/05 10:04:25, 5] lib/util.c:show_msg(464)
  size=35
  smb_com=0xb
  smb_rcls=2
  smb_reh=0
  smb_err=65
  smb_flg=128
  smb_flg2=32769
  smb_tid=1
  smb_pid=5311
  smb_uid=100
  smb_mid=45569
  smt_wct=0
  smb_bcc=0
[2005/10/05 10:04:25, 10] lib/util_sock.c:read_smb_length_return_keepalive(615)
  got smb length of 41
[2005/10/05 10:04:25, 6] smbd/process.c:process_smb(1113)
  got message type 0x0 of len 0x29
[2005/10/05 10:04:25, 3] smbd/process.c:process_smb(1114)
  Transaction 27 of length 45
[2005/10/05 10:04:25, 5] lib/util.c:show_msg(454)
[2005/10/05 10:04:25, 5] lib/util.c:show_msg(464)
  size=41
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=0
  smb_flg2=32768
  smb_tid=1
  smb_pid=5311
  smb_uid=100
  smb_mid=45697
  smt_wct=3
  smb_vwv[ 0]= 4188 (0x105C)
  smb_vwv[ 1]=45880 (0xB338)
  smb_vwv[ 2]=17219 (0x4343)
  smb_bcc=0
[2005/10/05 10:04:25, 3] smbd/process.c:switch_message(900)
  switch message SMBclose (pid 92057) conn 0x8357828
[2005/10/05 10:04:25, 4] smbd/uid.c:change_to_user(217)
  change_to_user: Skipping user change - already user
[2005/10/05 10:04:25, 3] smbd/reply.c:reply_close(3247)
  close fd=28 fnum=4188 (numopen=1)
[2005/10/05 10:04:25, 10] locking/locking.c:del_share_entry(658)
  del_share_entry: num_share_modes = 1
[2005/10/05 10:04:25, 10] locking/locking.c:del_share_entry(663)
  del_share_entry: deleted share_mode_entry[0]: pid = 92057, share_access = 0x1, private_options = 0x0, access_mask = 0x12019f, port = 0x0, type= 0x0, file_id = 1, dev = 0x415, inode = 779539
[2005/10/05 10:04:25, 10] locking/locking.c:del_share_entry(673)
  del_share_entry: deleting entry 0
[2005/10/05 10:04:25, 10] locking/locking.c:del_share_entry(695)
  del_share_entry: Remaining table.
[2005/10/05 10:04:25, 10] smbd/close.c:close_normal_file(203)
  close_normal_file: share_entry_count = 0 for file 1/test.txt
[2005/10/05 10:04:25, 10] locking/posix.c:posix_locking_close_file(1249)
  posix_locking_close_file: file 1/test.txt has no outstanding locks.
[2005/10/05 10:04:25, 10] smbd/posix_acls.c:check_posix_acl_group_write(4048)
  check_posix_acl_group_write: ret = -1 before check_stat:
[2005/10/05 10:04:25, 10] smbd/posix_acls.c:check_posix_acl_group_write(4069)
  check_posix_acl_group_write: file 1/test.txt failed to match on user or group in token (ret = -1).
[2005/10/05 10:04:25, 10] smbd/posix_acls.c:check_posix_acl_group_write(4078)
  check_posix_acl_group_write: file 1/test.txt returning (ret = -1).
[2005/10/05 10:04:25, 4] smbd/dosmode.c:set_filetime(506)
  set_filetime(1/test.txt) failed: Operation not permitted
[2005/10/05 10:04:25, 2] smbd/close.c:close_normal_file(270)
  rc20 closed file 1/test.txt (numopen=0) 
[2005/10/05 10:04:25, 5] smbd/files.c:file_free(459)
  freed files structure 4188 (0 used)
[2005/10/05 10:04:25, 5] lib/util.c:show_msg(454)
[2005/10/05 10:04:25, 5] lib/util.c:show_msg(464)
  size=35
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=128
  smb_flg2=32769
  smb_tid=1
  smb_pid=5311
  smb_uid=100
  smb_mid=45697
  smt_wct=0
  smb_bcc=0