[2009/07/01 17:59:32.279369, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 74
[2009/07/01 17:59:32.279612,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0x4a
[2009/07/01 17:59:32.279679,  3] smbd/process.c:1433(process_smb)
  Transaction 20 of length 78 (0 toread)
[2009/07/01 17:59:32.279739,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.279783,  5] lib/util.c:642(show_msg)
  size=74
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=0
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=21
  smt_wct=15
  smb_vwv[ 0]=    8 (0x8)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]= 4000 (0xFA0)
  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]=    8 (0x8)
  smb_vwv[10]=   66 (0x42)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    5 (0x5)
  smb_bcc=9
[2009/07/01 17:59:32.280123, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 02 00 00 00 00 00   00                       ........ .
[2009/07/01 17:59:32.280232,  3] smbd/process.c:1251(switch_message)
  switch message SMBtrans2 (pid 5980) conn 0x809f50c8
[2009/07/01 17:59:32.280299,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/01 17:59:32.280376,  3] smbd/trans2.c:3952(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
[2009/07/01 17:59:32.280459,  5] smbd/filename.c:148(unix_convert)
  unix_convert called on file ""
[2009/07/01 17:59:32.280532,  5] smbd/filename.c:181(unix_convert)
  conversion finished "" -> .
[2009/07/01 17:59:32.280649, 10] locking/locking.c:891(fetch_share_mode_unlocked)
  fetch_share_mode_unlocked: no share_mode record around (file not open)
[2009/07/01 17:59:32.280709,  3] smbd/trans2.c:4066(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0
[2009/07/01 17:59:32.280771,  8] smbd/dosmode.c:474(dos_mode)
  dos_mode: .
[2009/07/01 17:59:32.280857,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
  dos_mode_from_sbuf returning d
[2009/07/01 17:59:32.280962,  8] smbd/dosmode.c:521(dos_mode)
  dos_mode returning d
[2009/07/01 17:59:32.281050, 10] smbd/trans2.c:3520(store_file_unix_basic)
  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
[2009/07/01 17:59:32.281107,  4] smbd/trans2.c:3521(store_file_unix_basic)
  store_file_unix_basic: st_mode=40777
[2009/07/01 17:59:32.281167,  4] smbd/trans2.c:4569(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo: SMB_QUERY_FILE_UNIX_BASIC 0=0, 1=0, 2=0, 3=0, 4=0, 5=0, 6=0, 7=0, 8=0, 9=0, 10=0, 11=0, 12=0, 13=0, 14=0, 15=0, 16=ffffffdf, 17=5b, 18=ffffff9f, 19=ffffffe4, 20=64, 21=fffffffa, 22=ffffffc9, 23=1, 24=28, 25=e, 26=8, 27=61, 28=ffffffd5, 29=fffffff9, 30=ffffffc9, 31=1, 32=ffffffdf, 33=5b, 34=ffffff9f, 35=ffffffe4, 36=64, 37=fffffffa, 38=ffffffc9, 39=1, 40=ffffffe8, 41=3, 42=0, 43=0, 44=0, 45=0, 46=0, 47=0, 48=64, 49=0, 50=0, 51=0, 52=0, 53=0, 54=0, 55=0, 56=1, 57=0, 58=0, 59=0, 60=0, 61=0, 62=0, 63=0, 64=0, 65=0, 66=0, 67=0, 68=0, 69=0, 70=0, 71=0, 72=0, 73=0, 74=0, 75=0, 76=ffffffbc, 77=6a, 78=ffffffc7, 79=e, 80=0, 81=0, 82=0, 83=0, 84=ffffffff, 85=1, 86=0, 87=0, 88=0, 89=0, 90=0, 91=0, 92=9, 93=0, 94=0, 95=0, 96=0, 97=0, 98=0, 99=0,
[2009/07/01 17:59:32.281482,  9] smbd/trans2.c:820(send_trans2_replies)
  t2_rep: params_sent_thistime = 2, data_sent_thistime = 100, useable_space = 16410
[2009/07/01 17:59:32.281543,  9] smbd/trans2.c:822(send_trans2_replies)
  t2_rep: params_to_send = 2, data_to_send = 100, paramsize = 2, datasize = 100
[2009/07/01 17:59:32.281599,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.281640,  5] lib/util.c:642(show_msg)
  size=160
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=128
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=21
  smt_wct=10
  smb_vwv[ 0]=    2 (0x2)
  smb_vwv[ 1]=  100 (0x64)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    2 (0x2)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=  100 (0x64)
  smb_vwv[ 7]=   60 (0x3C)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=105
[2009/07/01 17:59:32.281912, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 00 00 00 00 00 00   00 00 00 00 00 00 00 00   ........ ........
  [0010] 00 00 00 00 00 DF 5B 9F   E4 64 FA C9 01 28 0E 08   ......[. .d...(..
  [0020] 61 D5 F9 C9 01 DF 5B 9F   E4 64 FA C9 01 E8 03 00   a.....[. .d......
  [0030] 00 00 00 00 00 64 00 00   00 00 00 00 00 01 00 00   .....d.. ........
  [0040] 00 00 00 00 00 00 00 00   00 00 00 00 00 00 00 00   ........ ........
  [0050] 00 BC 6A C7 0E 00 00 00   00 FF 01 00 00 00 00 00   ..j..... ........
  [0060] 00 09 00 00 00 00 00 00   00                       ........ .
[2009/07/01 17:59:32.283078, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 90
[2009/07/01 17:59:32.283153,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0x5a
[2009/07/01 17:59:32.283211,  3] smbd/process.c:1433(process_smb)
  Transaction 21 of length 94 (0 toread)
[2009/07/01 17:59:32.283266,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.283306,  5] lib/util.c:642(show_msg)
  size=90
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=0
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=22
  smt_wct=15
  smb_vwv[ 0]=   24 (0x18)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]= 4000 (0xFA0)
  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]=   24 (0x18)
  smb_vwv[10]=   66 (0x42)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    5 (0x5)
  smb_bcc=25
[2009/07/01 17:59:32.283633, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 02 00 00 00 00 2F   00 74 00 73 00 74 00 2E   ......./ .t.s.t..
  [0010] 00 74 00 73 00 74 00 00   00                       .t.s.t.. .
[2009/07/01 17:59:32.283791,  3] smbd/process.c:1251(switch_message)
  switch message SMBtrans2 (pid 5980) conn 0x809f50c8
[2009/07/01 17:59:32.283851,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/01 17:59:32.283913,  3] smbd/trans2.c:3952(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
[2009/07/01 17:59:32.283982,  5] smbd/filename.c:148(unix_convert)
  unix_convert called on file "tst.tst"
[2009/07/01 17:59:32.284048,  5] smbd/filename.c:303(unix_convert)
  unix_convert begin: name = tst.tst, dirpath = , start = tst.tst
[2009/07/01 17:59:32.284111,  3] smbd/trans2.c:4037(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo: SMB_VFS_LSTAT of tst.tst failed (Datei oder Verzeichnis nicht gefunden)
[2009/07/01 17:59:32.284654,  3] smbd/error.c:153(reply_unix_error)
  unix_error_packet: error string = Datei oder Verzeichnis nicht gefunden
[2009/07/01 17:59:32.284727,  3] smbd/error.c:60(error_packet_set)
  error packet at smbd/trans2.c(4038) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND
[2009/07/01 17:59:32.284794,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.284881,  5] lib/util.c:642(show_msg)
  size=35
  smb_com=0x32
  smb_rcls=52
  smb_reh=0
  smb_err=49152
  smb_flg=128
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=22
  smt_wct=0
  smb_bcc=0
[2009/07/01 17:59:32.289055, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 110
[2009/07/01 17:59:32.289140,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0x6e
[2009/07/01 17:59:32.289198,  3] smbd/process.c:1433(process_smb)
  Transaction 22 of length 114 (0 toread)
[2009/07/01 17:59:32.289254,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.289294,  5] lib/util.c:642(show_msg)
  size=110
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=0
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=23
  smt_wct=15
  smb_vwv[ 0]=   24 (0x18)
  smb_vwv[ 1]=   18 (0x12)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]= 1000 (0x3E8)
  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]=   24 (0x18)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=   18 (0x12)
  smb_vwv[12]=   92 (0x5C)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    6 (0x6)
  smb_bcc=45
[2009/07/01 17:59:32.289625, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 00 09 02 00 00 00   00 2F 00 74 00 73 00 74   ........ ./.t.s.t
  [0010] 00 2E 00 74 00 73 00 74   00 00 00 E2 BB EF D4 12   ...t.s.t ........
  [0020] 00 00 00 A4 81 00 00 00   00 00 00 00 02           ........ .....
[2009/07/01 17:59:32.289848,  3] smbd/process.c:1251(switch_message)
  switch message SMBtrans2 (pid 5980) conn 0x809f50c8
[2009/07/01 17:59:32.289908,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/01 17:59:32.289994,  5] smbd/filename.c:148(unix_convert)
  unix_convert called on file "tst.tst"
[2009/07/01 17:59:32.290065,  5] smbd/filename.c:303(unix_convert)
  unix_convert begin: name = tst.tst, dirpath = , start = tst.tst
[2009/07/01 17:59:32.290131,  3] smbd/trans2.c:6882(call_trans2setfilepathinfo)
  call_trans2setfilepathinfo(6) tst.tst (fnum -1) info_level=521 totdata=18
[2009/07/01 17:59:32.290194, 10] smbd/trans2.c:6502(smb_posix_open)
  smb_posix_open: file tst.tst, smb_posix_flags = 16, mode 0644
[2009/07/01 17:59:32.290253, 10] smbd/open.c:3365(create_file_default)
  create_file: access_mask = 0x2 file_attributes = 0x10001a4, share_access = 0x7, create_disposition = 0x3 create_options = 0x40 oplock_request = 0x1 root_dir_fid = 0x0, ea_list = 0x(nil), sd = 0x(nil), create_file_flags = 0x0, fname = tst.tst
[2009/07/01 17:59:32.290339, 10] smbd/open.c:2896(create_file_unixpath)
  create_file_unixpath: access_mask = 0x2 file_attributes = 0x10001a4, share_access = 0x7, create_disposition = 0x3 create_options = 0x40 oplock_request = 0x1 ea_list = 0x(nil), sd = 0x(nil), fname = tst.tst
[2009/07/01 17:59:32.290432,  5] smbd/files.c:103(file_new)
  allocated file structure 211, fnum = 4307 (1 used)
[2009/07/01 17:59:32.290495, 10] smbd/open.c:1475(open_file_ntcreate)
  open_file_ntcreate: fname=tst.tst, dos_attrs=0x0 access_mask=0x2 share_access=0x7 create_disposition = 0x3 create_options=0x40 unix mode=0644 oplock_request=1
[2009/07/01 17:59:32.290572, 10] smbd/open.c:1653(open_file_ntcreate)
  open_file_ntcreate: fname=tst.tst, after mapping access_mask=0x2
[2009/07/01 17:59:32.290633,  4] smbd/open.c:1913(open_file_ntcreate)
  calling open_file with flags=0x1 flags2=0x40 mode=0644, access_mask = 0x2, open_access_mask = 0x2
[2009/07/01 17:59:32.290996, 10] smbd/open.c:160(fd_open)
  fd_open: name tst.tst, flags = 0400101 mode = 0644, fd = 29.
[2009/07/01 17:59:32.291072, 10] smbd/notify_internal.c:593(notify_trigger)
  notify_trigger called action=0x1, filter=0x1, path=/home/gk/cifstest/tst.tst
[2009/07/01 17:59:32.291148,  2] smbd/open.c:580(open_file)
  gk opened file tst.tst read=No write=Yes (numopen=1)
[2009/07/01 17:59:32.291235, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 0808000000000000FC19
[2009/07/01 17:59:32.291328, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0x80a0ef18
[2009/07/01 17:59:32.291443, 10] smbd/open.c:980(delay_for_oplocks)
  delay_for_oplocks: oplock type 0x1 on file tst.tst
[2009/07/01 17:59:32.291501, 10] smbd/open.c:980(delay_for_oplocks)
  delay_for_oplocks: oplock type 0x1 on file tst.tst
[2009/07/01 17:59:32.291580,  3] smbd/oplock_linux.c:123(linux_set_kernel_oplock)
  linux_set_kernel_oplock: got kernel oplock on file tst.tst, file_id = 808:ecb19fc:0 gen_id = 4
[2009/07/01 17:59:32.291669,  5] smbd/oplock.c:86(set_file_oplock)
  set_file_oplock: granted oplock on file tst.tst, 808:ecb19fc:0/4, tv_sec = 4a4b87e4, tv_usec = 46e71
[2009/07/01 17:59:32.291753, 10] locking/locking.c:716(unparse_share_modes)
  unparse_share_modes: del: 0, owrt: Mi 01 Jul 2009 17:59:32 CEST CEST cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num: 1
[2009/07/01 17:59:32.291950, 10] locking/locking.c:518(print_share_mode_table)
  print_share_mode_table: share_mode_entry[0]:  pid = 5980, share_access = 0x7, private_options = 0x40, access_mask = 0x82, mid = 0x0, type= 0x1, gen_id = 4, uid = 1000, flags = 1, file_id 808:ecb19fc:0
[2009/07/01 17:59:32.292030, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 0808000000000000FC19
[2009/07/01 17:59:32.292125, 10] smbd/open.c:3200(create_file_unixpath)
  create_file_unixpath: info=2
[2009/07/01 17:59:32.292186, 10] smbd/open.c:3495(create_file_default)
  create_file: info=2
[2009/07/01 17:59:32.292245, 10] smbd/trans2.c:3520(store_file_unix_basic)
  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
[2009/07/01 17:59:32.292299,  4] smbd/trans2.c:3521(store_file_unix_basic)
  store_file_unix_basic: st_mode=100644
[2009/07/01 17:59:32.292373,  9] smbd/trans2.c:820(send_trans2_replies)
  t2_rep: params_sent_thistime = 2, data_sent_thistime = 112, useable_space = 16410
[2009/07/01 17:59:32.292434,  9] smbd/trans2.c:822(send_trans2_replies)
  t2_rep: params_to_send = 2, data_to_send = 112, paramsize = 2, datasize = 112
[2009/07/01 17:59:32.292491,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.292533,  5] lib/util.c:642(show_msg)
  size=172
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=128
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=23
  smt_wct=10
  smb_vwv[ 0]=    2 (0x2)
  smb_vwv[ 1]=  112 (0x70)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    2 (0x2)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=  112 (0x70)
  smb_vwv[ 7]=   60 (0x3C)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=117
[2009/07/01 17:59:32.292814, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 00 00 00 01 00 D3   10 02 00 00 00 00 02 00   ........ ........
  [0010] 00 00 00 00 00 00 00 00   00 00 00 00 00 00 00 00   ........ ........
  [0020] 00 F2 D0 12 EC 64 FA C9   01 F2 D0 12 EC 64 FA C9   .....d.. .....d..
  [0030] 01 F2 D0 12 EC 64 FA C9   01 E8 03 00 00 00 00 00   .....d.. ........
  [0040] 00 64 00 00 00 00 00 00   00 00 00 00 00 00 00 00   .d...... ........
  [0050] 00 00 00 00 00 00 00 00   00 00 00 00 00 FC 19 CB   ........ ........
  [0060] 0E 00 00 00 00 A4 01 00   00 00 00 00 00 01 00 00   ........ ........
  [0070] 00 00 00 00 00                                    .....
[2009/07/01 17:59:32.295433, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 1088
[2009/07/01 17:59:32.295654,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0x440
[2009/07/01 17:59:32.295719,  3] smbd/process.c:1433(process_smb)
  Transaction 23 of length 1092 (0 toread)
[2009/07/01 17:59:32.295777,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.295823,  5] lib/util.c:642(show_msg)
  size=1088
  smb_com=0x2f
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=0
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=24
  smt_wct=14
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]= 4307 (0x10D3)
  smb_vwv[ 3]=    0 (0x0)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=65535 (0xFFFF)
  smb_vwv[ 6]=65535 (0xFFFF)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_vwv[10]= 1024 (0x400)
  smb_vwv[11]=   64 (0x40)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    0 (0x0)
  smb_bcc=1025
[2009/07/01 17:59:32.296206, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   .AAAAAAA AAAAAAAA
  [0010] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0020] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0030] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0040] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0050] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0060] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0070] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0080] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0090] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [00A0] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [00B0] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [00C0] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [00D0] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [00E0] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [00F0] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0100] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0110] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0120] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0130] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0140] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0150] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0160] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0170] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0180] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [0190] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [01A0] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [01B0] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [01C0] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [01D0] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [01E0] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
  [01F0] 41 41 41 41 41 41 41 41   41 41 41 41 41 41 41 41   AAAAAAAA AAAAAAAA
[2009/07/01 17:59:32.298155,  3] smbd/process.c:1251(switch_message)
  switch message SMBwriteX (pid 5980) conn 0x809f50c8
[2009/07/01 17:59:32.298224,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/01 17:59:32.298300, 10] locking/locking.c:112(strict_lock_default)
  is_locked: optimisation - exclusive oplock on file tst.tst
[2009/07/01 17:59:32.298358, 10] locking/locking.c:152(strict_lock_default)
  strict_lock_default: flavour = POSIX_LOCK brl start=0 len=1024 unlocked for fnum 4307 file tst.tst
[2009/07/01 17:59:32.298485, 10] smbd/aio.c:231(schedule_aio_write_and_X)
  schedule_aio_write_and_X: write size (1024) too small for minimum aio_write of 0
[2009/07/01 17:59:32.298581, 10] lib/events.c:287(s3_event_debug)
  s3_event: Added timed event "update_write_time_handler": 0x80a05838
[2009/07/01 17:59:32.298637,  8] smbd/dosmode.c:474(dos_mode)
  dos_mode: tst.tst
[2009/07/01 17:59:32.298728,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
  dos_mode_from_sbuf returning
[2009/07/01 17:59:32.298788,  8] smbd/dosmode.c:521(dos_mode)
  dos_mode returning
[2009/07/01 17:59:32.298845, 10] smbd/dosmode.c:554(file_set_dosmode)
  file_set_dosmode: setting dos mode 0x20 on file tst.tst
[2009/07/01 17:59:32.298961,  8] smbd/dosmode.c:474(dos_mode)
  dos_mode: tst.tst
[2009/07/01 17:59:32.299202,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
  dos_mode_from_sbuf returning
[2009/07/01 17:59:32.299262,  8] smbd/dosmode.c:521(dos_mode)
  dos_mode returning
[2009/07/01 17:59:32.299406, 10] smbd/dosmode.c:301(set_ea_dos_attribute)
  set_ea_dos_attribute: set EA 0x20 on file tst.tst
[2009/07/01 17:59:32.299469, 10] smbd/notify_internal.c:593(notify_trigger)
  notify_trigger called action=0x3, filter=0x4, path=/home/gk/cifstest/tst.tst
[2009/07/01 17:59:32.299542,  3] smbd/fileio.c:336(write_file)
  WRITECACHE: initwrites=0 abutted=0 total=0 nonop=0 allocated=0 active=0 direct=0 perfect=0 readhits=0
[2009/07/01 17:59:32.299609,  3] smbd/fileio.c:345(write_file)
  WRITECACHE: Flushes SEEK=0, READ=0, WRITE=0, READRAW=0, OPLOCK=0, CLOSE=0, SYNC=0
[2009/07/01 17:59:32.299717, 10] smbd/fileio.c:138(real_write_file)
  real_write_file (tst.tst): pos = 0, size = 1024, returned 1024
[2009/07/01 17:59:32.299790,  3] smbd/reply.c:4237(reply_write_and_X)
  writeX fnum=4307 num=1024 wrote=1024
[2009/07/01 17:59:32.300639, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 192
[2009/07/01 17:59:32.300723,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0xc0
[2009/07/01 17:59:32.300782,  3] smbd/process.c:1433(process_smb)
  Transaction 24 of length 196 (0 toread)
[2009/07/01 17:59:32.300837,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.300878,  5] lib/util.c:642(show_msg)
  size=192
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=0
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=25
  smt_wct=15
  smb_vwv[ 0]=   24 (0x18)
  smb_vwv[ 1]=  100 (0x64)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]= 1000 (0x3E8)
  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]=   24 (0x18)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=  100 (0x64)
  smb_vwv[12]=   92 (0x5C)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    6 (0x6)
  smb_bcc=127
[2009/07/01 17:59:32.301255, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 00 00 02 00 00 00   00 2F 00 74 00 73 00 74   ........ ./.t.s.t
  [0010] 00 2E 00 74 00 73 00 74   00 00 00 FF FF FF FF FF   ...t.s.t ........
  [0020] FF FF FF FF FF FF FF FF   FF FF FF 5C D5 EB 23 65   ........ ...\..#e
  [0030] FA C9 01 00 A7 6C AB F8   F9 C9 01 80 0C EA D8 B5   .....l.. ........
  [0040] BE B2 01 FF FF FF FF FF   FF FF FF FF FF FF FF FF   ........ ........
  [0050] FF FF FF 00 00 00 00 00   00 00 00 00 00 00 00 00   ........ ........
  [0060] 00 00 00 00 00 00 00 00   00 00 00 00 00 00 00 FF   ........ ........
  [0070] FF FF FF FF FF FF FF 00   00 00 00 00 00 00 00     ........ .......
[2009/07/01 17:59:32.301762,  3] smbd/process.c:1251(switch_message)
  switch message SMBtrans2 (pid 5980) conn 0x809f50c8
[2009/07/01 17:59:32.301825,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/01 17:59:32.301918,  5] smbd/filename.c:148(unix_convert)
  unix_convert called on file "tst.tst"
[2009/07/01 17:59:32.301995,  5] smbd/filename.c:297(unix_convert)
  conversion finished tst.tst -> tst.tst
[2009/07/01 17:59:32.302058,  3] smbd/trans2.c:6882(call_trans2setfilepathinfo)
  call_trans2setfilepathinfo(6) tst.tst (fnum -1) info_level=512 totdata=100
[2009/07/01 17:59:32.302124, 10] smbd/trans2.c:6091(smb_set_file_unix_basic)
  smb_set_file_unix_basic: SMB_SET_FILE_UNIX_BASIC: name = tst.tst size = 0, uid = 4294967295, gid = 4294967295, raw perms = 037777777777
[2009/07/01 17:59:32.302198,  6] smbd/trans2.c:5051(smb_set_file_size)
  smb_set_file_size: size: 1024 smb_set_filetime: actime: Wed Jul  1 05:04:38 2009

   smb_set_filetime: modtime: Mon Oct 10 18:53:33 1988

   smb_set_file_time: setting utimes to modified values.
[2009/07/01 17:59:32.302342,  6] smbd/dosmode.c:721(file_ntimes)
  file_ntime: actime: Wed Jul  1 05:04:38 2009
[2009/07/01 17:59:32.302405,  6] smbd/dosmode.c:723(file_ntimes)
  file_ntime: modtime: Mon Oct 10 18:53:33 1988
[2009/07/01 17:59:32.302468,  6] smbd/dosmode.c:725(file_ntimes)
  file_ntime: createtime: Thu Jan  1 01:00:00 1970
[2009/07/01 17:59:32.302602, 10] smbd/notify_internal.c:593(notify_trigger)
  notify_trigger called action=0x3, filter=0x20, path=/home/gk/cifstest/tst.tst
[2009/07/01 17:59:32.302667, 10] smbd/notify_internal.c:593(notify_trigger)
  notify_trigger called action=0x3, filter=0x10, path=/home/gk/cifstest/tst.tst
[2009/07/01 17:59:32.302733,  9] smbd/trans2.c:820(send_trans2_replies)
  t2_rep: params_sent_thistime = 2, data_sent_thistime = 0, useable_space = 16412
[2009/07/01 17:59:32.302792,  9] smbd/trans2.c:822(send_trans2_replies)
  t2_rep: params_to_send = 2, data_to_send = 0, paramsize = 2, datasize = 0
[2009/07/01 17:59:32.302849,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.302889,  5] lib/util.c:642(show_msg)
  size=58
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=128
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=25
  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/07/01 17:59:32.303166, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 00                                          ...
[2009/07/01 17:59:32.303932, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 192
[2009/07/01 17:59:32.304007,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0xc0
[2009/07/01 17:59:32.304064,  3] smbd/process.c:1433(process_smb)
  Transaction 25 of length 196 (0 toread)
[2009/07/01 17:59:32.304119,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.304159,  5] lib/util.c:642(show_msg)
  size=192
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=0
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=26
  smt_wct=15
  smb_vwv[ 0]=   24 (0x18)
  smb_vwv[ 1]=  100 (0x64)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]= 1000 (0x3E8)
  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]=   24 (0x18)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=  100 (0x64)
  smb_vwv[12]=   92 (0x5C)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    6 (0x6)
  smb_bcc=127
[2009/07/01 17:59:32.304494, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 00 00 02 00 00 00   00 2F 00 74 00 73 00 74   ........ ./.t.s.t
  [0010] 00 2E 00 74 00 73 00 74   00 00 00 FF FF FF FF FF   ...t.s.t ........
  [0020] FF FF FF FF FF FF FF FF   FF FF FF 94 71 EC 23 65   ........ ....q.#e
  [0030] FA C9 01 FF FF FF FF FF   FF FF FF FF FF FF FF FF   ........ ........
  [0040] FF FF FF FF FF FF FF FF   FF FF FF FF FF FF FF FF   ........ ........
  [0050] FF FF FF 00 00 00 00 00   00 00 00 00 00 00 00 00   ........ ........
  [0060] 00 00 00 00 00 00 00 00   00 00 00 00 00 00 00 B6   ........ ........
  [0070] 81 00 00 00 00 00 00 00   00 00 00 00 00 00 00     ........ .......
[2009/07/01 17:59:32.305030,  3] smbd/process.c:1251(switch_message)
  switch message SMBtrans2 (pid 5980) conn 0x809f50c8
[2009/07/01 17:59:32.305092,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/01 17:59:32.305165,  5] smbd/filename.c:148(unix_convert)
  unix_convert called on file "tst.tst"
[2009/07/01 17:59:32.305233,  5] smbd/filename.c:297(unix_convert)
  conversion finished tst.tst -> tst.tst
[2009/07/01 17:59:32.305295,  3] smbd/trans2.c:6882(call_trans2setfilepathinfo)
  call_trans2setfilepathinfo(6) tst.tst (fnum -1) info_level=512 totdata=100
[2009/07/01 17:59:32.305357, 10] smbd/trans2.c:6091(smb_set_file_unix_basic)
  smb_set_file_unix_basic: SMB_SET_FILE_UNIX_BASIC: name = tst.tst size = 0, uid = 4294967295, gid = 4294967295, raw perms = 0100666
[2009/07/01 17:59:32.305429, 10] smbd/trans2.c:6137(smb_set_file_unix_basic)
  smb_set_file_unix_basic: SMB_SET_FILE_UNIX_BASIC setting mode 0666 for file tst.tst
[2009/07/01 17:59:32.305595,  6] smbd/trans2.c:5051(smb_set_file_size)
  smb_set_file_size: size: 1024 smb_set_filetime: actime: Wed Jul  1 05:04:38 2009

   smb_set_filetime: modtime: Mon Oct 10 18:53:33 1988

   t2_rep: params_sent_thistime = 2, data_sent_thistime = 0, useable_space = 16412
[2009/07/01 17:59:32.305782,  9] smbd/trans2.c:822(send_trans2_replies)
  t2_rep: params_to_send = 2, data_to_send = 0, paramsize = 2, datasize = 0
[2009/07/01 17:59:32.305839,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.305881,  5] lib/util.c:642(show_msg)
  size=58
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=128
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=26
  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/07/01 17:59:32.306159, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 00                                          ...
[2009/07/01 17:59:32.306715, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 41
[2009/07/01 17:59:32.306802,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0x29
[2009/07/01 17:59:32.306863,  3] smbd/process.c:1433(process_smb)
  Transaction 26 of length 45 (0 toread)
[2009/07/01 17:59:32.306918,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.306957,  5] lib/util.c:642(show_msg)
  size=41
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=0
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=27
  smt_wct=3
  smb_vwv[ 0]= 4307 (0x10D3)
  smb_vwv[ 1]=65535 (0xFFFF)
  smb_vwv[ 2]=65535 (0xFFFF)
  smb_bcc=0
[2009/07/01 17:59:32.307159,  3] smbd/process.c:1251(switch_message)
  switch message SMBclose (pid 5980) conn 0x809f50c8
[2009/07/01 17:59:32.307220,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/01 17:59:32.307281,  3] smbd/reply.c:4454(reply_close)
  close fd=29 fnum=4307 (numopen=1)
[2009/07/01 17:59:32.307337,  6] smbd/close.c:454(set_close_write_time)
  close_write_time: Thu Jan  1 00:59:59 1970
[2009/07/01 17:59:32.307411, 10] smbd/oplock_linux.c:135(linux_release_kernel_oplock)
  linux_release_kernel_oplock: file tst.tst, file_id = 808:ecb19fc:0 gen_id = 4 has kernel oplock state of 1.
[2009/07/01 17:59:32.307538, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 0808000000000000FC19
[2009/07/01 17:59:32.307634, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0x80a0ed98
[2009/07/01 17:59:32.307695, 10] locking/locking.c:552(parse_share_modes)
  parse_share_modes: delete_on_close: 0, owrt: Mi 01 Jul 2009 17:59:32 CEST CEST, cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num_share_modes: 1
[2009/07/01 17:59:32.307885, 10] locking/locking.c:649(parse_share_modes)
  parse_share_modes: share_mode_entry[0]:  pid = 5980, share_access = 0x7, private_options = 0x40, access_mask = 0x82, mid = 0x0, type= 0x1, gen_id = 4, uid = 1000, flags = 1, file_id 808:ecb19fc:0
[2009/07/01 17:59:32.307969, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 0808000000000000FC19
[2009/07/01 17:59:32.308068, 10] locking/posix.c:495(get_windows_lock_ref_count)
  get_windows_lock_count for file tst.tst = 0
[2009/07/01 17:59:32.308149, 10] locking/posix.c:521(delete_windows_lock_ref_count)
  delete_windows_lock_ref_count for file tst.tst
[2009/07/01 17:59:32.308332,  5] smbd/trans2.c:4922(smb_set_file_time)
  smb_set_filetime: actime: Wed Jul  1 05:04:38 2009

   smb_set_filetime: modtime: Wed Jul  1 17:59:32 2009

   smb_set_file_time: setting pending modtime to Wed Jul  1 17:59:32 2009

[2009/07/01 17:59:32.308473, 10] lib/events.c:287(s3_event_debug)
  s3_event: Destroying timer event 0x80a05838 "update_write_time_handler"
[2009/07/01 17:59:32.308531,  5] locking/locking.c:1425(set_sticky_write_time)
  set_sticky_write_time: Mi 01 Jul 2009 17:59:32 CEST CEST id=808:ecb19fc:0
[2009/07/01 17:59:32.308662, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 0808000000000000FC19
[2009/07/01 17:59:32.308770, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0x80a003e0
[2009/07/01 17:59:32.308831,  3] locking/locking.c:858(get_share_mode_lock)
  fill_share_mode_lock failed
[2009/07/01 17:59:32.308886, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 0808000000000000FC19
[2009/07/01 17:59:32.310419, 10] smbd/trans2.c:4972(smb_set_file_time)
  smb_set_file_time: setting utimes to modified values.
[2009/07/01 17:59:32.310544,  6] smbd/dosmode.c:721(file_ntimes)
  file_ntime: actime: Wed Jul  1 05:04:38 2009
[2009/07/01 17:59:32.310617,  6] smbd/dosmode.c:723(file_ntimes)
  file_ntime: modtime: Wed Jul  1 17:59:32 2009
[2009/07/01 17:59:32.310681,  6] smbd/dosmode.c:725(file_ntimes)
  file_ntime: createtime: Thu Jan  1 01:00:00 1970
[2009/07/01 17:59:32.310789, 10] smbd/notify_internal.c:593(notify_trigger)
  notify_trigger called action=0x3, filter=0x10, path=/home/gk/cifstest/tst.tst
[2009/07/01 17:59:32.310858,  2] smbd/close.c:612(close_normal_file)
  gk closed file tst.tst (numopen=0) NT_STATUS_OK
[2009/07/01 17:59:32.310925,  5] smbd/files.c:472(file_free)
  freed files structure 4307 (0 used)
[2009/07/01 17:59:32.310996,  5] lib/util.c:632(show_msg)
[2009/07/01 17:59:32.311038,  5] lib/util.c:642(show_msg)
  size=35
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=128
  smb_flg2=49153
  smb_tid=1
  smb_pid=3396
  smb_uid=100
  smb_mid=27
  smt_wct=0
  smb_bcc=0