Attachment 5265 Details for Bug 6925 – Logging output of Samba while running the test program

Logging output of Samba while running the test program

log.10.10.10.26 (text/plain), 58.50 KB, created by Jörg Sommer (mail address dead) on 2010-02-03 11:48:11 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jörg Sommer (mail address dead)

Created: 2010-02-03 11:48:11 UTC

Size: 58.50 KB

patch

obsolete

>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/02/03 18:31:59,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 1
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/02/03 18:31:59,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/02/03 18:31:59,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:572(pdb_set_username)
>  pdb_set_username: setting username joerg, was 
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:595(pdb_set_domain)
>  pdb_set_domain: setting domain NEPTUN, was 
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:618(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username , was 
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:641(pdb_set_fullname)
>  pdb_set_full_name: setting full name JÃ¶rg Sommer, was 
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:734(pdb_set_homedir)
>  pdb_set_homedir: setting home dir \\cifs-homes\all-homes\joerg, was 
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:710(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:664(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/02/03 18:31:59,  4] lib/substitute.c:504(automount_server)
>  Home server: neptun
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:687(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path \\neptun\joerg\profile, was 
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:777(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2010/02/03 18:31:59,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 2
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2010/02/03 18:31:59,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/02/03 18:31:59,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/02/03 18:31:59, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:501(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-3690565184-454682460-1052817659-3000
>[2010/02/03 18:31:59, 10] passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3690565184-454682460-1052817659-3000 from rid 3000
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (1000, 4) - sec_ctx_stack_ndx = 0
>[2010/02/03 18:31:59, 10] passdb/lookup_sid.c:1142(legacy_uid_to_sid)
>  LEGACY: uid 1000 -> sid S-1-5-21-3690565184-454682460-1052817659-3000
>[2010/02/03 18:31:59, 10] lib/gencache.c:194(gencache_get)
>  Cache entry with key = IDMAP/UID2SID/33 couldn't be found
>[2010/02/03 18:31:59,  5] passdb/lookup_sid.c:1325(uid_to_sid)
>  uid_to_sid: winbind failed to find a sid for uid 33
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(1000, 4) : sec_ctx_stack_ndx = 1
>[2010/02/03 18:31:59,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/02/03 18:31:59,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/02/03 18:31:59,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/02/03 18:31:59,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 1
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/02/03 18:31:59,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/02/03 18:31:59,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:572(pdb_set_username)
>  pdb_set_username: setting username www-data, was 
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:595(pdb_set_domain)
>  pdb_set_domain: setting domain NEPTUN, was 
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:618(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username , was 
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:641(pdb_set_fullname)
>  pdb_set_full_name: setting full name Webserver, was 
>[2010/02/03 18:31:59,  4] lib/substitute.c:504(automount_server)
>  Home server: neptun
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:734(pdb_set_homedir)
>  pdb_set_homedir: setting home dir \\neptun\www-data, was 
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:710(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:664(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2010/02/03 18:31:59,  4] lib/substitute.c:504(automount_server)
>  Home server: neptun
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:687(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path \\neptun\www-data\profile, was 
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:777(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2010/02/03 18:31:59,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 2
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2010/02/03 18:31:59,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/02/03 18:31:59,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/02/03 18:31:59, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/02/03 18:31:59, 10] passdb/pdb_get_set.c:501(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-3690565184-454682460-1052817659-1066
>[2010/02/03 18:31:59, 10] passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3690565184-454682460-1052817659-1066 from rid 1066
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (1000, 4) - sec_ctx_stack_ndx = 0
>[2010/02/03 18:31:59, 10] passdb/lookup_sid.c:1142(legacy_uid_to_sid)
>  LEGACY: uid 33 -> sid S-1-5-21-3690565184-454682460-1052817659-1066
>[2010/02/03 18:31:59, 10] smbd/posix_acls.c:2498(canonicalise_acl)
>  canonicalise_acl: Access ace entries before arrange :
>[2010/02/03 18:31:59, 10] smbd/posix_acls.c:2511(canonicalise_acl)
>  canon_ace index 0. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/02/03 18:31:59, 10] smbd/posix_acls.c:2511(canonicalise_acl)
>  canon_ace index 1. Type = allow SID = S-1-5-21-3690565184-454682460-1052817659-513 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms ---
>[2010/02/03 18:31:59, 10] smbd/posix_acls.c:2511(canonicalise_acl)
>  canon_ace index 2. Type = allow SID = S-1-5-21-3690565184-454682460-1052817659-1066 uid 33 (www-data) SMB_ACL_USER ace_flags = 0x0 perms --x
>[2010/02/03 18:31:59, 10] smbd/posix_acls.c:2511(canonicalise_acl)
>  canon_ace index 3. Type = allow SID = S-1-5-21-3690565184-454682460-1052817659-3000 uid 1000 (joerg) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>[2010/02/03 18:31:59, 10] smbd/posix_acls.c:817(print_canon_ace_list)
>  print_canon_ace_list: canonicalise_acl: ace entries after arrange
>  canon_ace index 0. Type = allow SID = S-1-5-21-3690565184-454682460-1052817659-3000 uid 1000 (joerg) SMB_ACL_USER_OBJ ace_flags = 0x0 perms rwx
>  canon_ace index 1. Type = allow SID = S-1-5-21-3690565184-454682460-1052817659-513 gid 100 (users) SMB_ACL_GROUP_OBJ ace_flags = 0x0 perms ---
>  canon_ace index 2. Type = allow SID = S-1-5-21-3690565184-454682460-1052817659-1066 uid 33 (www-data) SMB_ACL_USER ace_flags = 0x0 perms --x
>  canon_ace index 3. Type = allow SID = S-1-1-0 other SMB_ACL_OTHER ace_flags = 0x0 perms ---
>[2010/02/03 18:31:59, 10] smbd/posix_acls.c:1092(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 1c0 to (NT) 1f01ff
>[2010/02/03 18:31:59, 10] smbd/posix_acls.c:1092(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/02/03 18:31:59, 10] smbd/posix_acls.c:1092(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 40 to (NT) 1200a0
>[2010/02/03 18:31:59, 10] smbd/posix_acls.c:1092(map_canon_ace_perms)
>  map_canon_ace_perms: Mapped (UNIX) 0 to (NT) 0
>[2010/02/03 18:31:59,  4] smbd/open.c:1909(open_file_ntcreate)
>  calling open_file with flags=0x1 flags2=0x240 mode=0666, access_mask = 0x120116, open_access_mask = 0x120116
>[2010/02/03 18:31:59, 10] smbd/open.c:160(fd_open)
>  fd_open: name joerg/test, flags = 0101 mode = 0666, fd = 30. 
>[2010/02/03 18:31:59,  2] smbd/open.c:580(open_file)
>  joerg opened file joerg/test read=No write=Yes (numopen=1)
>[2010/02/03 18:31:59, 10] smbd/close.c:198(delete_all_streams)
>  delete_all_streams found 1 streams
>[2010/02/03 18:31:59,  5] smbd/oplock.c:86(set_file_oplock)
>  set_file_oplock: granted oplock on file joerg/test, 305:34dad6:0/1, tv_sec = 4b69b30f, tv_usec = 9a3f2
>[2010/02/03 18:31:59, 10] locking/locking.c:716(unparse_share_modes)
>  unparse_share_modes: del: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:518(print_share_mode_table)
>  print_share_mode_table: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] smbd/open.c:3197(create_file_unixpath)
>  create_file_unixpath: info=3
>[2010/02/03 18:31:59, 10] smbd/open.c:3492(create_file_default)
>  create_file: info=3
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:474(dos_mode)
>  dos_mode: joerg/test
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning 
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:521(dos_mode)
>  dos_mode returning 
>[2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive)
>  got smb length of 98
>[2010/02/03 18:31:59,  6] smbd/process.c:1456(process_smb)
>  got message type 0x0 of len 0x62
>[2010/02/03 18:31:59,  3] smbd/process.c:1459(process_smb)
>  Transaction 6 of length 102 (0 toread)
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=98
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=7
>  smt_wct=15
>  smb_vwv[ 0]=   30 (0x1E)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=   10 (0xA)
>  smb_vwv[ 3]=16644 (0x4104)
>  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]=   30 (0x1E)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=   98 (0x62)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    5 (0x5)
>  smb_bcc=33
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 44 20 07 01 00 00 00   00 5C 00 6A 00 6F 00 65   .D ..... .\.j.o.e
>  [0010] 00 72 00 67 00 5C 00 74   00 65 00 73 00 74 00 00   .r.g.\.t .e.s.t..
>  [0020] 00                                                . 
>[2010/02/03 18:31:59,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 18788) conn 0x89fd6b0
>[2010/02/03 18:31:59,  4] smbd/uid.c:256(change_to_user)
>  change_to_user: Skipping user change - already user
>[2010/02/03 18:31:59,  3] smbd/trans2.c:3955(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263
>[2010/02/03 18:31:59,  5] smbd/filename.c:148(unix_convert)
>  unix_convert called on file "joerg/test"
>[2010/02/03 18:31:59,  5] smbd/filename.c:297(unix_convert)
>  conversion finished joerg/test -> joerg/test
>[2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes)
>  parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num_share_modes: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes)
>  parse_share_modes: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59,  3] smbd/trans2.c:4069(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo joerg/test (fnum = -1) level=263 call=5 total_data=0
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:474(dos_mode)
>  dos_mode: joerg/test
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning 
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:521(dos_mode)
>  dos_mode returning 
>[2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file)
>  get_ea_list_from_file: ea_namelist size = 0
>[2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION
>[2010/02/03 18:31:59,  9] smbd/trans2.c:820(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010
>[2010/02/03 18:31:59,  9] smbd/trans2.c:822(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=154
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=7
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   94 (0x5E)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   94 (0x5E)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=99
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 00 00 00 00 80 4F 1D   D9 F5 A4 CA 01 80 4F 1D   ......O. ......O.
>  [0010] D9 F5 A4 CA 01 00 59 AA   FE F5 A4 CA 01 00 59 AA   ......Y. ......Y.
>  [0020] FE F5 A4 CA 01 80 00 00   00 00 00 00 00 00 00 00   ........ ........
>  [0030] 00 00 00 00 00 00 00 00   00 00 00 00 00 01 00 00   ........ ........
>  [0040] 00 00 00 00 00 00 00 00   00 16 00 00 00 5C 00 6A   ........ .....\.j
>  [0050] 00 6F 00 65 00 72 00 67   00 5C 00 74 00 65 00 73   .o.e.r.g .\.t.e.s
>  [0060] 00 74 00                                          .t. 
>[2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive)
>  got smb length of 72
>[2010/02/03 18:31:59,  6] smbd/process.c:1456(process_smb)
>  got message type 0x0 of len 0x48
>[2010/02/03 18:31:59,  3] smbd/process.c:1459(process_smb)
>  Transaction 7 of length 76 (0 toread)
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=72
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=8
>  smt_wct=15
>  smb_vwv[ 0]=    4 (0x4)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=16644 (0x4104)
>  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]=   72 (0x48)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    7 (0x7)
>  smb_bcc=7
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 44 20 6B 12 07 01                              .D k... 
>[2010/02/03 18:31:59,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 18788) conn 0x89fd6b0
>[2010/02/03 18:31:59,  4] smbd/uid.c:256(change_to_user)
>  change_to_user: Skipping user change - already user
>[2010/02/03 18:31:59,  3] smbd/trans2.c:3879(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 263
>[2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes)
>  parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num_share_modes: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes)
>  parse_share_modes: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59,  3] smbd/trans2.c:4069(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo joerg/test (fnum = 4715) level=263 call=7 total_data=0
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:474(dos_mode)
>  dos_mode: joerg/test
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning 
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:521(dos_mode)
>  dos_mode returning 
>[2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file)
>  get_ea_list_from_file: ea_namelist size = 0
>[2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION
>[2010/02/03 18:31:59,  9] smbd/trans2.c:820(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010
>[2010/02/03 18:31:59,  9] smbd/trans2.c:822(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=154
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=8
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   94 (0x5E)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   94 (0x5E)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=99
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 00 00 00 00 80 4F 1D   D9 F5 A4 CA 01 80 4F 1D   ......O. ......O.
>  [0010] D9 F5 A4 CA 01 00 59 AA   FE F5 A4 CA 01 00 59 AA   ......Y. ......Y.
>  [0020] FE F5 A4 CA 01 80 00 00   00 00 00 00 00 00 00 00   ........ ........
>  [0030] 00 00 00 00 00 00 00 00   00 00 00 00 00 01 00 00   ........ ........
>  [0040] 00 00 00 00 00 00 00 00   00 16 00 00 00 5C 00 6A   ........ .....\.j
>  [0050] 00 6F 00 65 00 72 00 67   00 5C 00 74 00 65 00 73   .o.e.r.g .\.t.e.s
>  [0060] 00 74 00                                          .t. 
>[2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive)
>  got smb length of 138
>[2010/02/03 18:31:59,  6] smbd/process.c:1456(process_smb)
>  got message type 0x0 of len 0x8a
>[2010/02/03 18:31:59,  3] smbd/process.c:1459(process_smb)
>  Transaction 8 of length 142 (0 toread)
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=138
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=9
>  smt_wct=15
>  smb_vwv[ 0]=   30 (0x1E)
>  smb_vwv[ 1]=   40 (0x28)
>  smb_vwv[ 2]=   10 (0xA)
>  smb_vwv[ 3]=16644 (0x4104)
>  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]=   30 (0x1E)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=   40 (0x28)
>  smb_vwv[12]=   98 (0x62)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    6 (0x6)
>  smb_bcc=73
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 44 20 EC 03 00 00 00   00 5C 00 6A 00 6F 00 65   .D ..... .\.j.o.e
>  [0010] 00 72 00 67 00 5C 00 74   00 65 00 73 00 74 00 00   .r.g.\.t .e.s.t..
>  [0020] 00 00 00 00 00 00 00 00   00 80 AA 31 A0 F6 A4 CA   ........ ...1....
>  [0030] 01 80 F6 6C 9B F6 A4 CA   01 00 00 00 00 00 00 00   ...l.... ........
>  [0040] 00 00 00 00 00 00 00 00   00                       ........ .
>[2010/02/03 18:31:59,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 18788) conn 0x89fd6b0
>[2010/02/03 18:31:59,  4] smbd/uid.c:256(change_to_user)
>  change_to_user: Skipping user change - already user
>[2010/02/03 18:31:59,  5] smbd/filename.c:148(unix_convert)
>  unix_convert called on file "joerg/test"
>[2010/02/03 18:31:59,  5] smbd/filename.c:297(unix_convert)
>  conversion finished joerg/test -> joerg/test
>[2010/02/03 18:31:59,  3] smbd/trans2.c:6899(call_trans2setfilepathinfo)
>  call_trans2setfilepathinfo(6) joerg/test (fnum -1) info_level=1004 totdata=40
>[2010/02/03 18:31:59,  6] smbd/trans2.c:5029(smb_set_file_dosmode)
>  smb_set_file_dosmode: dosmode: 0x0
>[2010/02/03 18:31:59, 10] smbd/trans2.c:5797(smb_set_file_basic_info)
>  smb_set_file_basic_info: file joerg/test
>[2010/02/03 18:31:59,  5] smbd/trans2.c:4939(smb_set_file_time)
>  smb_set_filetime: actime: Wed Feb  3 18:30:49 2010
>  
>   smb_set_filetime: modtime: Wed Feb  3 18:30:41 2010
>  
>   smb_set_file_time: createtime: Wed Feb  3 18:25:15 2010
>  
>   smb_set_file_time: setting pending modtime to Wed Feb  3 18:30:41 2010
>  
>[2010/02/03 18:31:59,  5] locking/locking.c:1425(set_sticky_write_time)
>  set_sticky_write_time: Mi 03 Feb 2010 18:30:41 CET CET id=305:34dad6:0
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0x89f8fe0
>[2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes)
>  parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num_share_modes: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes)
>  parse_share_modes: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59, 10] locking/locking.c:716(unparse_share_modes)
>  unparse_share_modes: del: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:518(print_share_mode_table)
>  print_share_mode_table: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] smbd/trans2.c:4989(smb_set_file_time)
>  smb_set_file_time: setting utimes to modified values.
>[2010/02/03 18:31:59,  6] smbd/dosmode.c:721(file_ntimes)
>  file_ntime: actime: Wed Feb  3 18:30:49 2010
>[2010/02/03 18:31:59,  6] smbd/dosmode.c:723(file_ntimes)
>  file_ntime: modtime: Wed Feb  3 18:30:41 2010
>[2010/02/03 18:31:59,  6] smbd/dosmode.c:725(file_ntimes)
>  file_ntime: createtime: Wed Feb  3 18:25:15 2010
>[2010/02/03 18:31:59, 10] smbd/notify_internal.c:593(notify_trigger)
>  notify_trigger called action=0x3, filter=0x30, path=/home/joerg/test
>[2010/02/03 18:31:59, 10] smbd/notify_internal.c:177(notify_load)
>  notify_load:
>[2010/02/03 18:31:59,  1] ../librpc/ndr/ndr.c:214(ndr_print_debug)
>       notify->array: struct notify_array
>          num_depths               : 0x00000000 (0)
>          depth: ARRAY(0)
>[2010/02/03 18:31:59,  9] smbd/trans2.c:820(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 0, useable_space = 131012
>[2010/02/03 18:31:59,  9] smbd/trans2.c:822(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 0, paramsize = 2, datasize = 0
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  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=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=9
>  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
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 00 00                                          ... 
>[2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive)
>  got smb length of 98
>[2010/02/03 18:31:59,  6] smbd/process.c:1456(process_smb)
>  got message type 0x0 of len 0x62
>[2010/02/03 18:31:59,  3] smbd/process.c:1459(process_smb)
>  Transaction 9 of length 102 (0 toread)
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=98
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=10
>  smt_wct=15
>  smb_vwv[ 0]=   30 (0x1E)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=   10 (0xA)
>  smb_vwv[ 3]=16644 (0x4104)
>  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]=   30 (0x1E)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=   98 (0x62)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    5 (0x5)
>  smb_bcc=33
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 44 20 07 01 00 00 00   00 5C 00 6A 00 6F 00 65   .D ..... .\.j.o.e
>  [0010] 00 72 00 67 00 5C 00 74   00 65 00 73 00 74 00 00   .r.g.\.t .e.s.t..
>  [0020] 00                                                . 
>[2010/02/03 18:31:59,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 18788) conn 0x89fd6b0
>[2010/02/03 18:31:59,  4] smbd/uid.c:256(change_to_user)
>  change_to_user: Skipping user change - already user
>[2010/02/03 18:31:59,  3] smbd/trans2.c:3955(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263
>[2010/02/03 18:31:59,  5] smbd/filename.c:148(unix_convert)
>  unix_convert called on file "joerg/test"
>[2010/02/03 18:31:59,  5] smbd/filename.c:297(unix_convert)
>  conversion finished joerg/test -> joerg/test
>[2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes)
>  parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes)
>  parse_share_modes: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59,  3] smbd/trans2.c:4069(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo joerg/test (fnum = -1) level=263 call=5 total_data=0
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:474(dos_mode)
>  dos_mode: joerg/test
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning 
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:521(dos_mode)
>  dos_mode returning 
>[2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file)
>  get_ea_list_from_file: ea_namelist size = 0
>[2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION
>[2010/02/03 18:31:59,  9] smbd/trans2.c:820(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010
>[2010/02/03 18:31:59,  9] smbd/trans2.c:822(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=154
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=10
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   94 (0x5E)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   94 (0x5E)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=99
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 00 00 00 00 80 F6 6C   9B F6 A4 CA 01 80 AA 31   .......l .......1
>  [0010] A0 F6 A4 CA 01 80 F6 6C   9B F6 A4 CA 01 80 F6 6C   .......l .......l
>  [0020] 9B F6 A4 CA 01 80 00 00   00 00 00 00 00 00 00 00   ........ ........
>  [0030] 00 00 00 00 00 00 00 00   00 00 00 00 00 01 00 00   ........ ........
>  [0040] 00 00 00 00 00 00 00 00   00 16 00 00 00 5C 00 6A   ........ .....\.j
>  [0050] 00 6F 00 65 00 72 00 67   00 5C 00 74 00 65 00 73   .o.e.r.g .\.t.e.s
>  [0060] 00 74 00                                          .t. 
>[2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive)
>  got smb length of 72
>[2010/02/03 18:31:59,  6] smbd/process.c:1456(process_smb)
>  got message type 0x0 of len 0x48
>[2010/02/03 18:31:59,  3] smbd/process.c:1459(process_smb)
>  Transaction 10 of length 76 (0 toread)
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=72
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=11
>  smt_wct=15
>  smb_vwv[ 0]=    4 (0x4)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=16644 (0x4104)
>  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]=   72 (0x48)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    7 (0x7)
>  smb_bcc=7
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 44 20 6B 12 07 01                              .D k... 
>[2010/02/03 18:31:59,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 18788) conn 0x89fd6b0
>[2010/02/03 18:31:59,  4] smbd/uid.c:256(change_to_user)
>  change_to_user: Skipping user change - already user
>[2010/02/03 18:31:59,  3] smbd/trans2.c:3879(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 263
>[2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes)
>  parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes)
>  parse_share_modes: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59,  3] smbd/trans2.c:4069(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo joerg/test (fnum = 4715) level=263 call=7 total_data=0
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:474(dos_mode)
>  dos_mode: joerg/test
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning 
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:521(dos_mode)
>  dos_mode returning 
>[2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file)
>  get_ea_list_from_file: ea_namelist size = 0
>[2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION
>[2010/02/03 18:31:59,  9] smbd/trans2.c:820(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010
>[2010/02/03 18:31:59,  9] smbd/trans2.c:822(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=154
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=11
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   94 (0x5E)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   94 (0x5E)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=99
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 00 00 00 00 80 F6 6C   9B F6 A4 CA 01 80 AA 31   .......l .......1
>  [0010] A0 F6 A4 CA 01 80 F6 6C   9B F6 A4 CA 01 80 F6 6C   .......l .......l
>  [0020] 9B F6 A4 CA 01 80 00 00   00 00 00 00 00 00 00 00   ........ ........
>  [0030] 00 00 00 00 00 00 00 00   00 00 00 00 00 01 00 00   ........ ........
>  [0040] 00 00 00 00 00 00 00 00   00 16 00 00 00 5C 00 6A   ........ .....\.j
>  [0050] 00 6F 00 65 00 72 00 67   00 5C 00 74 00 65 00 73   .o.e.r.g .\.t.e.s
>  [0060] 00 74 00                                          .t. 
>[2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive)
>  got smb length of 76
>[2010/02/03 18:31:59,  6] smbd/process.c:1456(process_smb)
>  got message type 0x0 of len 0x4c
>[2010/02/03 18:31:59,  3] smbd/process.c:1459(process_smb)
>  Transaction 11 of length 80 (0 toread)
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=76
>  smb_com=0x2f
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=12
>  smt_wct=14
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]= 4715 (0x126B)
>  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]=    0 (0x0)
>  smb_vwv[10]=   12 (0xC)
>  smb_vwv[11]=   64 (0x40)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    0 (0x0)
>  smb_bcc=13
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 48 65 6C 6C 6F 20 77   6F 72 6C 64 0A           .Hello w orld.
>[2010/02/03 18:31:59,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 18788) conn 0x89fd6b0
>[2010/02/03 18:31:59,  4] smbd/uid.c:256(change_to_user)
>  change_to_user: Skipping user change - already user
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0x8a040b0
>[2010/02/03 18:31:59, 10] locking/brlock.c:1849(brl_get_locks_internal)
>  brl_get_locks_internal: 0 current locks on file_id 305:34dad6:0
>[2010/02/03 18:31:59, 10] locking/posix.c:284(is_posix_locked)
>  is_posix_locked: File joerg/test, offset = 0, count = 12, type = WRITE
>[2010/02/03 18:31:59, 10] locking/posix.c:172(posix_lock_in_range)
>  posix_lock_in_range: offset_out = 0, count_out = 12
>[2010/02/03 18:31:59,  8] locking/posix.c:234(posix_fcntl_getlock)
>  posix_fcntl_getlock 30 0 12 1
>[2010/02/03 18:31:59,  8] lib/util.c:1832(fcntl_getlock)
>  fcntl_getlock fd=30 offset=0 count=12 type=1
>[2010/02/03 18:31:59,  3] lib/util.c:1856(fcntl_getlock)
>  fcntl_getlock: fd 30 is returned info 2 pid 0
>[2010/02/03 18:31:59,  8] locking/posix.c:264(posix_fcntl_getlock)
>  posix_fcntl_getlock: Lock query call successful
>[2010/02/03 18:31:59, 10] locking/brlock.c:1270(brl_locktest)
>  brl_locktest: posix start=0 len=12 unlocked for fnum 4715 file joerg/test
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] locking/locking.c:152(strict_lock_default)
>  strict_lock_default: flavour = WINDOWS_LOCK brl start=0 len=12 unlocked for fnum 4715 file joerg/test
>[2010/02/03 18:31:59, 10] lib/events.c:287(s3_event_debug)
>  s3_event: Added timed event "update_write_time_handler": 0x89fc4f8
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:474(dos_mode)
>  dos_mode: joerg/test
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning 
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:521(dos_mode)
>  dos_mode returning 
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0x8a04290
>[2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes)
>  parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes)
>  parse_share_modes: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59, 10] smbd/oplock.c:769(contend_level2_oplocks_begin_default)
>  release_level_2_oplocks_on_change: num_share_modes = 1
>[2010/02/03 18:31:59, 10] smbd/oplock.c:792(contend_level2_oplocks_begin_default)
>  release_level_2_oplocks_on_change: share_entry[0]->op_type == 16
>[2010/02/03 18:31:59,  3] smbd/oplock.c:369(break_level2_to_none_async)
>  process_oplock_async_level2_break_message: downgrading fake level 2 oplock.
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0x8a04550
>[2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes)
>  parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes)
>  parse_share_modes: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x10, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59, 10] locking/locking.c:716(unparse_share_modes)
>  unparse_share_modes: del: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:518(print_share_mode_table)
>  print_share_mode_table: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x0, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] smbd/fileio.c:138(real_write_file)
>  real_write_file (joerg/test): pos = 0, size = 12, returned 12
>[2010/02/03 18:31:59,  3] smbd/reply.c:4266(reply_write_and_X)
>  writeX fnum=4715 num=12 wrote=12
>[2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive)
>  got smb length of 98
>[2010/02/03 18:31:59,  6] smbd/process.c:1456(process_smb)
>  got message type 0x0 of len 0x62
>[2010/02/03 18:31:59,  3] smbd/process.c:1459(process_smb)
>  Transaction 12 of length 102 (0 toread)
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=98
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=13
>  smt_wct=15
>  smb_vwv[ 0]=   30 (0x1E)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=   10 (0xA)
>  smb_vwv[ 3]=16644 (0x4104)
>  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]=   30 (0x1E)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=   98 (0x62)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    5 (0x5)
>  smb_bcc=33
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 44 20 07 01 00 00 00   00 5C 00 6A 00 6F 00 65   .D ..... .\.j.o.e
>  [0010] 00 72 00 67 00 5C 00 74   00 65 00 73 00 74 00 00   .r.g.\.t .e.s.t..
>  [0020] 00                                                . 
>[2010/02/03 18:31:59,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 18788) conn 0x89fd6b0
>[2010/02/03 18:31:59,  4] smbd/uid.c:256(change_to_user)
>  change_to_user: Skipping user change - already user
>[2010/02/03 18:31:59,  3] smbd/trans2.c:3955(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263
>[2010/02/03 18:31:59,  5] smbd/filename.c:148(unix_convert)
>  unix_convert called on file "joerg/test"
>[2010/02/03 18:31:59,  5] smbd/filename.c:297(unix_convert)
>  conversion finished joerg/test -> joerg/test
>[2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes)
>  parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes)
>  parse_share_modes: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x0, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59,  3] smbd/trans2.c:4069(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo joerg/test (fnum = -1) level=263 call=5 total_data=0
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:474(dos_mode)
>  dos_mode: joerg/test
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning 
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:521(dos_mode)
>  dos_mode returning 
>[2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file)
>  get_ea_list_from_file: ea_namelist size = 0
>[2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION
>[2010/02/03 18:31:59,  9] smbd/trans2.c:820(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010
>[2010/02/03 18:31:59,  9] smbd/trans2.c:822(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=154
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=13
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   94 (0x5E)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   94 (0x5E)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=99
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 00 00 00 00 80 AA 31   A0 F6 A4 CA 01 80 AA 31   .......1 .......1
>  [0010] A0 F6 A4 CA 01 80 F6 6C   9B F6 A4 CA 01 80 F6 6C   .......l .......l
>  [0020] 9B F6 A4 CA 01 80 00 00   00 00 00 00 00 00 10 00   ........ ........
>  [0030] 00 00 00 00 00 0C 00 00   00 00 00 00 00 01 00 00   ........ ........
>  [0040] 00 00 00 00 00 00 00 00   00 16 00 00 00 5C 00 6A   ........ .....\.j
>  [0050] 00 6F 00 65 00 72 00 67   00 5C 00 74 00 65 00 73   .o.e.r.g .\.t.e.s
>  [0060] 00 74 00                                          .t. 
>[2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive)
>  got smb length of 72
>[2010/02/03 18:31:59,  6] smbd/process.c:1456(process_smb)
>  got message type 0x0 of len 0x48
>[2010/02/03 18:31:59,  3] smbd/process.c:1459(process_smb)
>  Transaction 13 of length 76 (0 toread)
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=72
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=14
>  smt_wct=15
>  smb_vwv[ 0]=    4 (0x4)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=16644 (0x4104)
>  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]=   72 (0x48)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    7 (0x7)
>  smb_bcc=7
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 44 20 6B 12 07 01                              .D k... 
>[2010/02/03 18:31:59,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 18788) conn 0x89fd6b0
>[2010/02/03 18:31:59,  4] smbd/uid.c:256(change_to_user)
>  change_to_user: Skipping user change - already user
>[2010/02/03 18:31:59,  3] smbd/trans2.c:3879(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 263
>[2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes)
>  parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes)
>  parse_share_modes: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x0, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59,  3] smbd/trans2.c:4069(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo joerg/test (fnum = 4715) level=263 call=7 total_data=0
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:474(dos_mode)
>  dos_mode: joerg/test
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning 
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:521(dos_mode)
>  dos_mode returning 
>[2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file)
>  get_ea_list_from_file: ea_namelist size = 0
>[2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION
>[2010/02/03 18:31:59,  9] smbd/trans2.c:820(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010
>[2010/02/03 18:31:59,  9] smbd/trans2.c:822(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=154
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=14
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   94 (0x5E)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   94 (0x5E)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=99
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 00 00 00 00 80 AA 31   A0 F6 A4 CA 01 80 AA 31   .......1 .......1
>  [0010] A0 F6 A4 CA 01 80 F6 6C   9B F6 A4 CA 01 80 F6 6C   .......l .......l
>  [0020] 9B F6 A4 CA 01 80 00 00   00 00 00 00 00 00 10 00   ........ ........
>  [0030] 00 00 00 00 00 0C 00 00   00 00 00 00 00 01 00 00   ........ ........
>  [0040] 00 00 00 00 00 00 00 00   00 16 00 00 00 5C 00 6A   ........ .....\.j
>  [0050] 00 6F 00 65 00 72 00 67   00 5C 00 74 00 65 00 73   .o.e.r.g .\.t.e.s
>  [0060] 00 74 00                                          .t. 
>[2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive)
>  got smb length of 41
>[2010/02/03 18:31:59,  6] smbd/process.c:1456(process_smb)
>  got message type 0x0 of len 0x29
>[2010/02/03 18:31:59,  3] smbd/process.c:1459(process_smb)
>  Transaction 14 of length 45 (0 toread)
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  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=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=15
>  smt_wct=3
>  smb_vwv[ 0]= 4715 (0x126B)
>  smb_vwv[ 1]=65535 (0xFFFF)
>  smb_vwv[ 2]=65535 (0xFFFF)
>  smb_bcc=0
>[2010/02/03 18:31:59,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 18788) conn 0x89fd6b0
>[2010/02/03 18:31:59,  4] smbd/uid.c:256(change_to_user)
>  change_to_user: Skipping user change - already user
>[2010/02/03 18:31:59,  3] smbd/reply.c:4483(reply_close)
>  close fd=30 fnum=4715 (numopen=1)
>[2010/02/03 18:31:59,  6] smbd/close.c:454(set_close_write_time)
>  close_write_time: Thu Jan  1 00:59:59 1970
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0x8a040b0
>[2010/02/03 18:31:59, 10] locking/locking.c:552(parse_share_modes)
>  parse_share_modes: delete_on_close: 0, owrt: Mi 03 Feb 2010 18:26:18 CET CET, cwrt: Mi 03 Feb 2010 18:30:41 CET CET, tok: 0, num_share_modes: 1
>[2010/02/03 18:31:59, 10] locking/locking.c:649(parse_share_modes)
>  parse_share_modes: share_mode_entry[0]:  pid = 18788, share_access = 0x3, private_options = 0x40, access_mask = 0x120196, mid = 0x0, type= 0x0, gen_id = 1, uid = 1000, flags = 0, file_id 305:34dad6:0
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] locking/posix.c:495(get_windows_lock_ref_count)
>  get_windows_lock_count for file joerg/test = 0
>[2010/02/03 18:31:59, 10] locking/posix.c:521(delete_windows_lock_ref_count)
>  delete_windows_lock_ref_count for file joerg/test
>[2010/02/03 18:31:59,  5] smbd/trans2.c:4939(smb_set_file_time)
>  smb_set_filetime: actime: Wed Feb  3 18:30:49 2010
>  
>   smb_set_filetime: modtime: Wed Feb  3 18:32:00 2010
>  
>   smb_set_file_time: createtime: Wed Feb  3 18:30:49 2010
>  
>   smb_set_file_time: setting pending modtime to Wed Feb  3 18:32:00 2010
>  
>[2010/02/03 18:31:59, 10] lib/events.c:287(s3_event_debug)
>  s3_event: Destroying timer event 0x89fc4f8 "update_write_time_handler"
>[2010/02/03 18:31:59,  5] locking/locking.c:1425(set_sticky_write_time)
>  set_sticky_write_time: Mi 03 Feb 2010 18:32:00 CET CET id=305:34dad6:0
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0x89fa188
>[2010/02/03 18:31:59,  3] locking/locking.c:858(get_share_mode_lock)
>  fill_share_mode_lock failed
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 0503000000000000D6DA
>[2010/02/03 18:31:59, 10] smbd/trans2.c:4989(smb_set_file_time)
>  smb_set_file_time: setting utimes to modified values.
>[2010/02/03 18:31:59,  6] smbd/dosmode.c:721(file_ntimes)
>  file_ntime: actime: Wed Feb  3 18:30:49 2010
>[2010/02/03 18:31:59,  6] smbd/dosmode.c:723(file_ntimes)
>  file_ntime: modtime: Wed Feb  3 18:32:00 2010
>[2010/02/03 18:31:59,  6] smbd/dosmode.c:725(file_ntimes)
>  file_ntime: createtime: Wed Feb  3 18:30:49 2010
>[2010/02/03 18:31:59, 10] smbd/notify_internal.c:593(notify_trigger)
>  notify_trigger called action=0x3, filter=0x10, path=/home/joerg/test
>[2010/02/03 18:31:59,  2] smbd/close.c:612(close_normal_file)
>  joerg closed file joerg/test (numopen=0) NT_STATUS_OK
>[2010/02/03 18:31:59,  5] smbd/files.c:476(file_free)
>  freed files structure 4715 (0 used)
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  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=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=15
>  smt_wct=0
>  smb_bcc=0
>[2010/02/03 18:31:59, 10] lib/util_sock.c:789(read_smb_length_return_keepalive)
>  got smb length of 98
>[2010/02/03 18:31:59,  6] smbd/process.c:1456(process_smb)
>  got message type 0x0 of len 0x62
>[2010/02/03 18:31:59,  3] smbd/process.c:1459(process_smb)
>  Transaction 15 of length 102 (0 toread)
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=98
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=16
>  smt_wct=15
>  smb_vwv[ 0]=   30 (0x1E)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=   10 (0xA)
>  smb_vwv[ 3]=16644 (0x4104)
>  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]=   30 (0x1E)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=   98 (0x62)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    5 (0x5)
>  smb_bcc=33
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 44 20 07 01 00 00 00   00 5C 00 6A 00 6F 00 65   .D ..... .\.j.o.e
>  [0010] 00 72 00 67 00 5C 00 74   00 65 00 73 00 74 00 00   .r.g.\.t .e.s.t..
>  [0020] 00                                                . 
>[2010/02/03 18:31:59,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 18788) conn 0x89fd6b0
>[2010/02/03 18:31:59,  4] smbd/uid.c:256(change_to_user)
>  change_to_user: Skipping user change - already user
>[2010/02/03 18:31:59,  3] smbd/trans2.c:3955(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 263
>[2010/02/03 18:31:59,  5] smbd/filename.c:148(unix_convert)
>  unix_convert called on file "joerg/test"
>[2010/02/03 18:31:59,  5] smbd/filename.c:297(unix_convert)
>  conversion finished joerg/test -> joerg/test
>[2010/02/03 18:31:59, 10] locking/locking.c:891(fetch_share_mode_unlocked)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2010/02/03 18:31:59,  3] smbd/trans2.c:4069(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo joerg/test (fnum = -1) level=263 call=5 total_data=0
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:474(dos_mode)
>  dos_mode: joerg/test
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:175(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning 
>[2010/02/03 18:31:59,  8] smbd/dosmode.c:521(dos_mode)
>  dos_mode returning 
>[2010/02/03 18:31:59, 10] smbd/trans2.c:204(get_ea_names_from_file)
>  get_ea_list_from_file: ea_namelist size = 0
>[2010/02/03 18:31:59, 10] smbd/trans2.c:4404(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: SMB_FILE_ALL_INFORMATION
>[2010/02/03 18:31:59,  9] smbd/trans2.c:820(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 94, useable_space = 131010
>[2010/02/03 18:31:59,  9] smbd/trans2.c:822(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 94, paramsize = 2, datasize = 94
>[2010/02/03 18:31:59,  5] lib/util.c:632(show_msg)
>[2010/02/03 18:31:59,  5] lib/util.c:642(show_msg)
>  size=154
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=8542
>  smb_uid=100
>  smb_mid=16
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   94 (0x5E)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   94 (0x5E)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=99
>[2010/02/03 18:31:59, 10] ../lib/util/util.c:304(_dump_data)
>  [0000] 00 00 00 00 00 80 AA 31   A0 F6 A4 CA 01 80 AA 31   .......1 .......1
>  [0010] A0 F6 A4 CA 01 00 68 83   CA F6 A4 CA 01 00 68 83   ......h. ......h.
>  [0020] CA F6 A4 CA 01 80 00 00   00 00 00 00 00 00 10 00   ........ ........
>  [0030] 00 00 00 00 00 0C 00 00   00 00 00 00 00 01 00 00   ........ ........
>  [0040] 00 00 00 00 00 00 00 00   00 16 00 00 00 5C 00 6A   ........ .....\.j
>  [0050] 00 6F 00 65 00 72 00 67   00 5C 00 74 00 65 00 73   .o.e.r.g .\.t.e.s
>  [0060] 00 74 00                                          .t. 
>[2010/02/03 18:31:59,  5] lib/util_sock.c:528(read_fd_with_timeout)
>  read_fd_with_timeout: blocking read. EOF from client.
>[2010/02/03 18:31:59, 10] smbd/process.c:271(receive_smb_raw_talloc)
>  receive_smb_raw: NT_STATUS_END_OF_FILE
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/02/03 18:31:59,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/02/03 18:31:59,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/02/03 18:31:59,  5] smbd/uid.c:368(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/02/03 18:31:59,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/02/03 18:31:59,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/02/03 18:31:59,  5] smbd/uid.c:368(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/02/03 18:31:59,  1] smbd/service.c:1226(close_cnum)
>  joerg (::ffff:10.10.10.26) closed connection to service all-homes
>[2010/02/03 18:31:59,  3] smbd/connection.c:31(yield_connection)
>  Yielding connection to all-homes
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 64490000FFFFFFFF0100
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0x89f9138
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 64490000FFFFFFFF0100
>[2010/02/03 18:31:59,  4] smbd/vfs.c:753(vfs_ChDir)
>  vfs_ChDir to /
>[2010/02/03 18:31:59,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/02/03 18:31:59,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2010/02/03 18:31:59,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2010/02/03 18:31:59,  5] smbd/uid.c:368(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2010/02/03 18:31:59,  5] lib/messages.c:329(messaging_deregister)
>  Deregistering messaging pointer for type 784 - private_data=0x89f20e0
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 49442F31383738382F31
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0x89fd6b0
>[2010/02/03 18:31:59,  4] auth/pampass.c:472(smb_pam_start)
>  smb_pam_start: PAM: Init user: joerg
>[2010/02/03 18:31:59,  4] auth/pampass.c:489(smb_pam_start)
>  smb_pam_start: PAM: setting rhost to: ::ffff:10.10.10.26
>[2010/02/03 18:31:59,  4] auth/pampass.c:498(smb_pam_start)
>  smb_pam_start: PAM: setting tty
>[2010/02/03 18:31:59,  4] auth/pampass.c:506(smb_pam_start)
>  smb_pam_start: PAM: Init passed for user: joerg
>[2010/02/03 18:31:59,  4] auth/pampass.c:643(smb_internal_pam_session)
>  smb_internal_pam_session: PAM: tty set to: smb/18788/100
>[2010/02/03 18:31:59,  4] auth/pampass.c:450(smb_pam_end)
>  smb_pam_end: PAM: PAM_END OK.
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 49442F31383738382F31
>[2010/02/03 18:31:59,  3] smbd/connection.c:31(yield_connection)
>  Yielding connection to 
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 64490000FFFFFFFFFFFF
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0x89f9138
>[2010/02/03 18:31:59, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 64490000FFFFFFFFFFFF
>[2010/02/03 18:31:59,  3] smbd/server.c:849(exit_server_common)
>  Server exit (failed to receive smb request)

Actions: View

Attachments on bug 6925: 4994 | 4995 | 4996 | 5063 | 5265 | 5266