[2009/07/02 00:01:18.767803, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 74
[2009/07/02 00:01:18.768046,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0x4a
[2009/07/02 00:01:18.768114,  3] smbd/process.c:1433(process_smb)
  Transaction 29 of length 78 (0 toread)
[2009/07/02 00:01:18.768175,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.768221,  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=4057
  smb_uid=100
  smb_mid=30
  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/02 00:01:18.768631, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 02 00 00 00 00 00   00                       ........ .
[2009/07/02 00:01:18.768740,  3] smbd/process.c:1251(switch_message)
  switch message SMBtrans2 (pid 8568) conn 0x809f50c8
[2009/07/02 00:01:18.768807,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/02 00:01:18.768887,  3] smbd/trans2.c:3952(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
[2009/07/02 00:01:18.768970,  5] smbd/filename.c:148(unix_convert)
  unix_convert called on file ""
[2009/07/02 00:01:18.769042,  5] smbd/filename.c:181(unix_convert)
  conversion finished "" -> .
[2009/07/02 00:01:18.769162, 10] locking/locking.c:891(fetch_share_mode_unlocked)
  fetch_share_mode_unlocked: no share_mode record around (file not open)
[2009/07/02 00:01:18.769224,  3] smbd/trans2.c:4066(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0
[2009/07/02 00:01:18.769286,  8] smbd/dosmode.c:474(dos_mode)
  dos_mode: .
[2009/07/02 00:01:18.769371,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
  dos_mode_from_sbuf returning d
[2009/07/02 00:01:18.769434,  8] smbd/dosmode.c:521(dos_mode)
  dos_mode returning d
[2009/07/02 00:01:18.769522, 10] smbd/trans2.c:3520(store_file_unix_basic)
  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
[2009/07/02 00:01:18.769579,  4] smbd/trans2.c:3521(store_file_unix_basic)
  store_file_unix_basic: st_mode=40777
[2009/07/02 00:01:18.769639,  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=59, 17=18, 18=60, 19=6a, 20=ffffff97, 21=fffffffa, 22=ffffffc9, 23=1, 24=ffffffed, 25=9, 26=ffffffb6, 27=ffffff9c, 28=ffffff95, 29=fffffffa, 30=ffffffc9, 31=1, 32=59, 33=18, 34=60, 35=6a, 36=ffffff97, 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/02 00:01:18.769907,  9] smbd/trans2.c:820(send_trans2_replies)
  t2_rep: params_sent_thistime = 2, data_sent_thistime = 100, useable_space = 16410
[2009/07/02 00:01:18.769967,  9] smbd/trans2.c:822(send_trans2_replies)
  t2_rep: params_to_send = 2, data_to_send = 100, paramsize = 2, datasize = 100
[2009/07/02 00:01:18.770024,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.770065,  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=4057
  smb_uid=100
  smb_mid=30
  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/02 00:01:18.770461, 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 59 18 60   6A 97 FA C9 01 ED 09 B6   .....Y.` j.......
  [0020] 9C 95 FA C9 01 59 18 60   6A 97 FA C9 01 E8 03 00   .....Y.` j.......
  [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/02 00:01:18.771728, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 90
[2009/07/02 00:01:18.771808,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0x5a
[2009/07/02 00:01:18.771867,  3] smbd/process.c:1433(process_smb)
  Transaction 30 of length 94 (0 toread)
[2009/07/02 00:01:18.771924,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.771965,  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=4057
  smb_uid=100
  smb_mid=31
  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/02 00:01:18.772291, 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/02 00:01:18.772442,  3] smbd/process.c:1251(switch_message)
  switch message SMBtrans2 (pid 8568) conn 0x809f50c8
[2009/07/02 00:01:18.772503,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/02 00:01:18.772569,  3] smbd/trans2.c:3952(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
[2009/07/02 00:01:18.772642,  5] smbd/filename.c:148(unix_convert)
  unix_convert called on file "tst.tst"
[2009/07/02 00:01:18.772711,  5] smbd/filename.c:303(unix_convert)
  unix_convert begin: name = tst.tst, dirpath = , start = tst.tst
[2009/07/02 00:01:18.772778,  3] smbd/trans2.c:4037(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo: SMB_VFS_LSTAT of tst.tst failed (Datei oder Verzeichnis nicht gefunden)
[2009/07/02 00:01:18.772849,  3] smbd/error.c:153(reply_unix_error)
  unix_error_packet: error string = Datei oder Verzeichnis nicht gefunden
[2009/07/02 00:01:18.772908,  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/02 00:01:18.772972,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.773014,  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=4057
  smb_uid=100
  smb_mid=31
  smt_wct=0
  smb_bcc=0
[2009/07/02 00:01:18.773692, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 110
[2009/07/02 00:01:18.773767,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0x6e
[2009/07/02 00:01:18.773823,  3] smbd/process.c:1433(process_smb)
  Transaction 31 of length 114 (0 toread)
[2009/07/02 00:01:18.773877,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.773918,  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=4057
  smb_uid=100
  smb_mid=32
  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/02 00:01:18.774250, 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/02 00:01:18.774451,  3] smbd/process.c:1251(switch_message)
  switch message SMBtrans2 (pid 8568) conn 0x809f50c8
[2009/07/02 00:01:18.774549,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/02 00:01:18.774621,  5] smbd/filename.c:148(unix_convert)
  unix_convert called on file "tst.tst"
[2009/07/02 00:01:18.774690,  5] smbd/filename.c:303(unix_convert)
  unix_convert begin: name = tst.tst, dirpath = , start = tst.tst
[2009/07/02 00:01:18.774752,  3] smbd/trans2.c:6882(call_trans2setfilepathinfo)
  call_trans2setfilepathinfo(6) tst.tst (fnum -1) info_level=521 totdata=18
[2009/07/02 00:01:18.774871, 10] smbd/trans2.c:6502(smb_posix_open)
  smb_posix_open: file tst.tst, smb_posix_flags = 16, mode 0644
[2009/07/02 00:01:18.774935, 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/02 00:01:18.775021, 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/02 00:01:18.775114,  5] smbd/files.c:103(file_new)
  allocated file structure 67, fnum = 4163 (1 used)
[2009/07/02 00:01:18.775179, 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/02 00:01:18.775256, 10] smbd/open.c:1653(open_file_ntcreate)
  open_file_ntcreate: fname=tst.tst, after mapping access_mask=0x2
[2009/07/02 00:01:18.775317,  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/02 00:01:18.775712, 10] smbd/open.c:160(fd_open)
  fd_open: name tst.tst, flags = 0400101 mode = 0644, fd = 29.
[2009/07/02 00:01:18.775787, 10] smbd/notify_internal.c:593(notify_trigger)
  notify_trigger called action=0x1, filter=0x1, path=/home/gk/cifstest/tst.tst
[2009/07/02 00:01:18.775863,  2] smbd/open.c:580(open_file)
  gk opened file tst.tst read=No write=Yes (numopen=1)
[2009/07/02 00:01:18.775951, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 0808000000000000FC19
[2009/07/02 00:01:18.776046, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0x80a0ef18
[2009/07/02 00:01:18.776111, 10] smbd/open.c:980(delay_for_oplocks)
  delay_for_oplocks: oplock type 0x1 on file tst.tst
[2009/07/02 00:01:18.776169, 10] smbd/open.c:980(delay_for_oplocks)
  delay_for_oplocks: oplock type 0x1 on file tst.tst
[2009/07/02 00:01:18.776248,  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 = 6
[2009/07/02 00:01:18.776322,  5] smbd/oplock.c:86(set_file_oplock)
  set_file_oplock: granted oplock on file tst.tst, 808:ecb19fc:0/6, tv_sec = 4a4bdcae, tv_usec = bd3bc
[2009/07/02 00:01:18.776408, 10] locking/locking.c:716(unparse_share_modes)
  unparse_share_modes: del: 0, owrt: Do 02 Jul 2009 00:01:18 CEST CEST cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num: 1
[2009/07/02 00:01:18.776605, 10] locking/locking.c:518(print_share_mode_table)
  print_share_mode_table: share_mode_entry[0]:  pid = 8568, share_access = 0x7, private_options = 0x40, access_mask = 0x82, mid = 0x0, type= 0x1, gen_id = 6, uid = 1000, flags = 1, file_id 808:ecb19fc:0
[2009/07/02 00:01:18.776687, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 0808000000000000FC19
[2009/07/02 00:01:18.776785, 10] smbd/open.c:3200(create_file_unixpath)
  create_file_unixpath: info=2
[2009/07/02 00:01:18.776846, 10] smbd/open.c:3495(create_file_default)
  create_file: info=2
[2009/07/02 00:01:18.776906, 10] smbd/trans2.c:3520(store_file_unix_basic)
  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
[2009/07/02 00:01:18.777009,  4] smbd/trans2.c:3521(store_file_unix_basic)
  store_file_unix_basic: st_mode=100644
[2009/07/02 00:01:18.777087,  9] smbd/trans2.c:820(send_trans2_replies)
  t2_rep: params_sent_thistime = 2, data_sent_thistime = 112, useable_space = 16410
[2009/07/02 00:01:18.777148,  9] smbd/trans2.c:822(send_trans2_replies)
  t2_rep: params_to_send = 2, data_to_send = 112, paramsize = 2, datasize = 112
[2009/07/02 00:01:18.777206,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.777248,  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=4057
  smb_uid=100
  smb_mid=32
  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/02 00:01:18.777532, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 00 00 00 01 00 43   10 02 00 00 00 00 02 00   .......C ........
  [0010] 00 00 00 00 00 00 00 00   00 00 00 00 00 00 00 00   ........ ........
  [0020] 00 82 BA FF 75 97 FA C9   01 82 BA FF 75 97 FA C9   ....u... ....u...
  [0030] 01 82 BA FF 75 97 FA C9   01 E8 03 00 00 00 00 00   ....u... ........
  [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/02 00:01:18.780098, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 1088
[2009/07/02 00:01:18.780196,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0x440
[2009/07/02 00:01:18.780259,  3] smbd/process.c:1433(process_smb)
  Transaction 32 of length 1092 (0 toread)
[2009/07/02 00:01:18.780316,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.780357,  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=4057
  smb_uid=100
  smb_mid=33
  smt_wct=14
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]= 4163 (0x1043)
  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/02 00:01:18.780686, 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/02 00:01:18.782549,  3] smbd/process.c:1251(switch_message)
  switch message SMBwriteX (pid 8568) conn 0x809f50c8
[2009/07/02 00:01:18.782615,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/02 00:01:18.782691, 10] locking/locking.c:112(strict_lock_default)
  is_locked: optimisation - exclusive oplock on file tst.tst
[2009/07/02 00:01:18.782747, 10] locking/locking.c:152(strict_lock_default)
  strict_lock_default: flavour = POSIX_LOCK brl start=0 len=1024 unlocked for fnum 4163 file tst.tst
[2009/07/02 00:01:18.782882, 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/02 00:01:18.782976, 10] lib/events.c:287(s3_event_debug)
  s3_event: Added timed event "update_write_time_handler": 0x809fb350
[2009/07/02 00:01:18.783032,  8] smbd/dosmode.c:474(dos_mode)
  dos_mode: tst.tst
[2009/07/02 00:01:18.783120,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
  dos_mode_from_sbuf returning
[2009/07/02 00:01:18.783181,  8] smbd/dosmode.c:521(dos_mode)
  dos_mode returning
[2009/07/02 00:01:18.783242, 10] smbd/dosmode.c:554(file_set_dosmode)
  file_set_dosmode: setting dos mode 0x20 on file tst.tst
[2009/07/02 00:01:18.783358,  8] smbd/dosmode.c:474(dos_mode)
  dos_mode: tst.tst
[2009/07/02 00:01:18.783424,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
  dos_mode_from_sbuf returning
[2009/07/02 00:01:18.783627,  8] smbd/dosmode.c:521(dos_mode)
  dos_mode returning
[2009/07/02 00:01:18.783770, 10] smbd/dosmode.c:301(set_ea_dos_attribute)
  set_ea_dos_attribute: set EA 0x20 on file tst.tst
[2009/07/02 00:01:18.783835, 10] smbd/notify_internal.c:593(notify_trigger)
  notify_trigger called action=0x3, filter=0x4, path=/home/gk/cifstest/tst.tst
[2009/07/02 00:01:18.783906,  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/02 00:01:18.783971,  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/02 00:01:18.784078, 10] smbd/fileio.c:138(real_write_file)
  real_write_file (tst.tst): pos = 0, size = 1024, returned 1024
[2009/07/02 00:01:18.784150,  3] smbd/reply.c:4237(reply_write_and_X)
  writeX fnum=4163 num=1024 wrote=1024
[2009/07/02 00:01:18.784999, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 192
[2009/07/02 00:01:18.785082,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0xc0
[2009/07/02 00:01:18.785142,  3] smbd/process.c:1433(process_smb)
  Transaction 33 of length 196 (0 toread)
[2009/07/02 00:01:18.785199,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.785242,  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=4057
  smb_uid=100
  smb_mid=34
  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/02 00:01:18.785617, 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 1C 3E F6 AE 97   ........ ....>...
  [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/02 00:01:18.786098,  3] smbd/process.c:1251(switch_message)
  switch message SMBtrans2 (pid 8568) conn 0x809f50c8
[2009/07/02 00:01:18.786163,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/02 00:01:18.786258,  5] smbd/filename.c:148(unix_convert)
  unix_convert called on file "tst.tst"
[2009/07/02 00:01:18.786335,  5] smbd/filename.c:297(unix_convert)
  conversion finished tst.tst -> tst.tst
[2009/07/02 00:01:18.786399,  3] smbd/trans2.c:6882(call_trans2setfilepathinfo)
  call_trans2setfilepathinfo(6) tst.tst (fnum -1) info_level=512 totdata=100
[2009/07/02 00:01:18.786465, 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/02 00:01:18.786542,  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/02 00:01:18.786690,  6] smbd/dosmode.c:721(file_ntimes)
  file_ntime: actime: Wed Jul  1 05:04:38 2009
[2009/07/02 00:01:18.786757,  6] smbd/dosmode.c:723(file_ntimes)
  file_ntime: modtime: Mon Oct 10 18:53:33 1988
[2009/07/02 00:01:18.786875,  6] smbd/dosmode.c:725(file_ntimes)
  file_ntime: createtime: Thu Jan  1 01:00:00 1970
[2009/07/02 00:01:18.786975, 10] smbd/notify_internal.c:593(notify_trigger)
  notify_trigger called action=0x3, filter=0x20, path=/home/gk/cifstest/tst.tst
[2009/07/02 00:01:18.787040, 10] smbd/notify_internal.c:593(notify_trigger)
  notify_trigger called action=0x3, filter=0x10, path=/home/gk/cifstest/tst.tst
[2009/07/02 00:01:18.787107,  9] smbd/trans2.c:820(send_trans2_replies)
  t2_rep: params_sent_thistime = 2, data_sent_thistime = 0, useable_space = 16412
[2009/07/02 00:01:18.787165,  9] smbd/trans2.c:822(send_trans2_replies)
  t2_rep: params_to_send = 2, data_to_send = 0, paramsize = 2, datasize = 0
[2009/07/02 00:01:18.787221,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.787263,  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=4057
  smb_uid=100
  smb_mid=34
  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/02 00:01:18.787537, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 00                                          ...
[2009/07/02 00:01:18.788466, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 192
[2009/07/02 00:01:18.788555,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0xc0
[2009/07/02 00:01:18.788615,  3] smbd/process.c:1433(process_smb)
  Transaction 34 of length 196 (0 toread)
[2009/07/02 00:01:18.788723,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.788766,  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=4057
  smb_uid=100
  smb_mid=35
  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/02 00:01:18.789101, 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 DA F6 AE 97   ........ ...\....
  [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/02 00:01:18.789582,  3] smbd/process.c:1251(switch_message)
  switch message SMBtrans2 (pid 8568) conn 0x809f50c8
[2009/07/02 00:01:18.789645,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/02 00:01:18.789735,  5] smbd/filename.c:148(unix_convert)
  unix_convert called on file "tst.tst"
[2009/07/02 00:01:18.789810,  5] smbd/filename.c:297(unix_convert)
  conversion finished tst.tst -> tst.tst
[2009/07/02 00:01:18.789875,  3] smbd/trans2.c:6882(call_trans2setfilepathinfo)
  call_trans2setfilepathinfo(6) tst.tst (fnum -1) info_level=512 totdata=100
[2009/07/02 00:01:18.789940, 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/02 00:01:18.790021, 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/02 00:01:18.790198,  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/02 00:01:18.790359,  9] smbd/trans2.c:822(send_trans2_replies)
  t2_rep: params_to_send = 2, data_to_send = 0, paramsize = 2, datasize = 0
[2009/07/02 00:01:18.790419,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.790460,  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=4057
  smb_uid=100
  smb_mid=35
  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/02 00:01:18.790732, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 00                                          ...
[2009/07/02 00:01:18.791353, 10] lib/util_sock.c:781(read_smb_length_return_keepalive)
  got smb length of 41
[2009/07/02 00:01:18.791446,  6] smbd/process.c:1430(process_smb)
  got message type 0x0 of len 0x29
[2009/07/02 00:01:18.791505,  3] smbd/process.c:1433(process_smb)
  Transaction 35 of length 45 (0 toread)
[2009/07/02 00:01:18.791561,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.791602,  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=4057
  smb_uid=100
  smb_mid=36
  smt_wct=3
  smb_vwv[ 0]= 4163 (0x1043)
  smb_vwv[ 1]=65535 (0xFFFF)
  smb_vwv[ 2]=65535 (0xFFFF)
  smb_bcc=0
[2009/07/02 00:01:18.791865,  3] smbd/process.c:1251(switch_message)
  switch message SMBclose (pid 8568) conn 0x809f50c8
[2009/07/02 00:01:18.791928,  4] smbd/uid.c:256(change_to_user)
  change_to_user: Skipping user change - already user
[2009/07/02 00:01:18.791990,  3] smbd/reply.c:4454(reply_close)
  close fd=29 fnum=4163 (numopen=1)
[2009/07/02 00:01:18.792048,  6] smbd/close.c:454(set_close_write_time)
  close_write_time: Thu Jan  1 00:59:59 1970
[2009/07/02 00:01:18.792125, 10] smbd/oplock_linux.c:135(linux_release_kernel_oplock)
  linux_release_kernel_oplock: file tst.tst, file_id = 808:ecb19fc:0 gen_id = 6 has kernel oplock state of 1.
[2009/07/02 00:01:18.792254, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 0808000000000000FC19
[2009/07/02 00:01:18.792353, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0x80a0ed98
[2009/07/02 00:01:18.792415, 10] locking/locking.c:552(parse_share_modes)
  parse_share_modes: delete_on_close: 0, owrt: Do 02 Jul 2009 00:01:18 CEST CEST, cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num_share_modes: 1
[2009/07/02 00:01:18.792609, 10] locking/locking.c:649(parse_share_modes)
  parse_share_modes: share_mode_entry[0]:  pid = 8568, share_access = 0x7, private_options = 0x40, access_mask = 0x82, mid = 0x0, type= 0x1, gen_id = 6, uid = 1000, flags = 1, file_id 808:ecb19fc:0
[2009/07/02 00:01:18.792694, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 0808000000000000FC19
[2009/07/02 00:01:18.792795, 10] locking/posix.c:495(get_windows_lock_ref_count)
  get_windows_lock_count for file tst.tst = 0
[2009/07/02 00:01:18.792862, 10] locking/posix.c:521(delete_windows_lock_ref_count)
  delete_windows_lock_ref_count for file tst.tst
[2009/07/02 00:01:18.793038,  5] smbd/trans2.c:4922(smb_set_file_time)
  smb_set_filetime: actime: Wed Jul  1 05:04:38 2009

   smb_set_filetime: modtime: Thu Jul  2 00:01:19 2009

   smb_set_file_time: setting pending modtime to Thu Jul  2 00:01:19 2009

[2009/07/02 00:01:18.793187, 10] lib/events.c:287(s3_event_debug)
  s3_event: Destroying timer event 0x809fb350 "update_write_time_handler"
[2009/07/02 00:01:18.793245,  5] locking/locking.c:1425(set_sticky_write_time)
  set_sticky_write_time: Do 02 Jul 2009 00:01:19 CEST CEST id=808:ecb19fc:0
[2009/07/02 00:01:18.793378, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 0808000000000000FC19
[2009/07/02 00:01:18.793449, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0x80a00850
[2009/07/02 00:01:18.793507,  3] locking/locking.c:858(get_share_mode_lock)
  fill_share_mode_lock failed
[2009/07/02 00:01:18.793562, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 0808000000000000FC19
[2009/07/02 00:01:18.793646, 10] smbd/trans2.c:4972(smb_set_file_time)
  smb_set_file_time: setting utimes to modified values.
[2009/07/02 00:01:18.793704,  6] smbd/dosmode.c:721(file_ntimes)
  file_ntime: actime: Wed Jul  1 05:04:38 2009
[2009/07/02 00:01:18.793768,  6] smbd/dosmode.c:723(file_ntimes)
  file_ntime: modtime: Thu Jul  2 00:01:19 2009
[2009/07/02 00:01:18.793832,  6] smbd/dosmode.c:725(file_ntimes)
  file_ntime: createtime: Thu Jan  1 01:00:00 1970
[2009/07/02 00:01:18.793925, 10] smbd/notify_internal.c:593(notify_trigger)
  notify_trigger called action=0x3, filter=0x10, path=/home/gk/cifstest/tst.tst
[2009/07/02 00:01:18.793989,  2] smbd/close.c:612(close_normal_file)
  gk closed file tst.tst (numopen=0) NT_STATUS_OK
[2009/07/02 00:01:18.794053,  5] smbd/files.c:472(file_free)
  freed files structure 4163 (0 used)
[2009/07/02 00:01:18.794116,  5] lib/util.c:632(show_msg)
[2009/07/02 00:01:18.794159,  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=4057
  smb_uid=100
  smb_mid=36
  smt_wct=0
  smb_bcc=0