Attachment 6446 Details for Bug 8061 – Level 10 log of a "touch test" from the client

Level 10 log of a "touch test" from the client

smb.level10.log (text/plain), 137.95 KB, created by Daniel Bruckhaus on 2011-05-16 08:37:38 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Daniel Bruckhaus

Created: 2011-05-16 08:37:38 UTC

Size: 137.95 KB

patch

obsolete

>[2011/05/16 10:24:47.755838,  6] param/loadparm.c:7133(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Mon May 16 10:21:46 2011
>  
>[2011/05/16 10:24:47.756805,  3] smbd/oplock.c:895(init_oplocks)
>  init_oplocks: initializing messages.
>[2011/05/16 10:24:47.756832,  5] lib/messages.c:329(messaging_deregister)
>  Deregistering messaging pointer for type 1 - private_data=(nil)
>[2011/05/16 10:24:47.756860, 10] smbd/process.c:740(event_add_idle)
>  event_add_idle: idle_evt(keepalive) 0xb7784ef8
>[2011/05/16 10:24:47.756882, 10] smbd/process.c:740(event_add_idle)
>  event_add_idle: idle_evt(deadtime) 0xb7825698
>[2011/05/16 10:24:47.756902, 10] smbd/process.c:740(event_add_idle)
>  event_add_idle: idle_evt(housekeeping) 0xb780c978
>[2011/05/16 10:24:50.955114, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 78
>[2011/05/16 10:24:50.955167,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x4e
>[2011/05/16 10:24:50.955186,  3] smbd/process.c:1489(process_smb)
>  Transaction 0 of length 82 (0 toread)
>[2011/05/16 10:24:50.955204,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:24:50.955215,  5] lib/util.c:627(show_msg)
>  size=78
>  smb_com=0x72
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=0
>  smb_pid=1064
>  smb_uid=0
>  smb_mid=436
>  smt_wct=0
>  smb_bcc=43
>[2011/05/16 10:24:50.955280, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 02 4C 4D 31 2E 32 58 30   30 32 00 02 4C 41 4E 4D   .LM1.2X0 02..LANM
>  [0010] 41 4E 32 2E 31 00 02 4E   54 20 4C 4D 20 30 2E 31   AN2.1..N T LM 0.1
>  [0020] 32 00 02 50 4F 53 49 58   20 32 00                 2..POSIX  2.
>[2011/05/16 10:24:50.955370,  3] smbd/process.c:1298(switch_message)
>  switch message SMBnegprot (pid 9822) conn 0x0
>[2011/05/16 10:24:50.955392,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.955411,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.955428,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.955460,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2011/05/16 10:24:50.955546,  3] smbd/negprot.c:586(reply_negprot)
>  Requested protocol [LM1.2X002]
>[2011/05/16 10:24:50.955565,  3] smbd/negprot.c:586(reply_negprot)
>  Requested protocol [LANMAN2.1]
>[2011/05/16 10:24:50.955581,  3] smbd/negprot.c:586(reply_negprot)
>  Requested protocol [NT LM 0.12]
>[2011/05/16 10:24:50.955598,  3] smbd/negprot.c:586(reply_negprot)
>  Requested protocol [POSIX 2]
>[2011/05/16 10:24:50.955617, 10] lib/util.c:1969(set_remote_arch)
>  set_remote_arch: Client arch is 'CIFSFS'
>[2011/05/16 10:24:50.955652,  6] param/loadparm.c:7133(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Mon May 16 10:21:46 2011
>  
>[2011/05/16 10:24:50.955689,  5] smbd/connection.c:142(claim_connection)
>  claiming []
>[2011/05/16 10:24:50.955802, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 5E260000FFFFFFFF0000
>[2011/05/16 10:24:50.955830, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0xb7819fd8
>[2011/05/16 10:24:50.955877, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 5E260000FFFFFFFF0000
>[2011/05/16 10:24:50.955976,  6] param/loadparm.c:7133(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Mon May 16 10:21:46 2011
>  
>[2011/05/16 10:24:50.956016, 10] smbd/negprot.c:41(get_challenge)
>  get challenge: creating negprot_global_auth_context
>[2011/05/16 10:24:50.956034,  5] auth/auth.c:487(make_auth_context_subsystem)
>  Making default auth method list for standalone security=user, encrypt passwords = yes
>[2011/05/16 10:24:50.956059,  5] auth/auth.c:46(smb_register_auth)
>  Attempting to register auth backend sam
>[2011/05/16 10:24:50.956075,  5] auth/auth.c:58(smb_register_auth)
>  Successfully added auth method 'sam'
>[2011/05/16 10:24:50.956109,  5] auth/auth.c:46(smb_register_auth)
>  Attempting to register auth backend sam_ignoredomain
>[2011/05/16 10:24:50.956125,  5] auth/auth.c:58(smb_register_auth)
>  Successfully added auth method 'sam_ignoredomain'
>[2011/05/16 10:24:50.956142,  5] auth/auth.c:46(smb_register_auth)
>  Attempting to register auth backend unix
>[2011/05/16 10:24:50.956158,  5] auth/auth.c:58(smb_register_auth)
>  Successfully added auth method 'unix'
>[2011/05/16 10:24:50.956173,  5] auth/auth.c:46(smb_register_auth)
>  Attempting to register auth backend winbind
>[2011/05/16 10:24:50.956189,  5] auth/auth.c:58(smb_register_auth)
>  Successfully added auth method 'winbind'
>[2011/05/16 10:24:50.956204,  5] auth/auth.c:46(smb_register_auth)
>  Attempting to register auth backend wbc
>[2011/05/16 10:24:50.956220,  5] auth/auth.c:58(smb_register_auth)
>  Successfully added auth method 'wbc'
>[2011/05/16 10:24:50.956235,  5] auth/auth.c:46(smb_register_auth)
>  Attempting to register auth backend smbserver
>[2011/05/16 10:24:50.956250,  5] auth/auth.c:58(smb_register_auth)
>  Successfully added auth method 'smbserver'
>[2011/05/16 10:24:50.956267,  5] auth/auth.c:46(smb_register_auth)
>  Attempting to register auth backend trustdomain
>[2011/05/16 10:24:50.956282,  5] auth/auth.c:58(smb_register_auth)
>  Successfully added auth method 'trustdomain'
>[2011/05/16 10:24:50.956298,  5] auth/auth.c:46(smb_register_auth)
>  Attempting to register auth backend ntdomain
>[2011/05/16 10:24:50.956313,  5] auth/auth.c:58(smb_register_auth)
>  Successfully added auth method 'ntdomain'
>[2011/05/16 10:24:50.956329,  5] auth/auth.c:46(smb_register_auth)
>  Attempting to register auth backend guest
>[2011/05/16 10:24:50.956345,  5] auth/auth.c:58(smb_register_auth)
>  Successfully added auth method 'guest'
>[2011/05/16 10:24:50.956360,  5] auth/auth.c:46(smb_register_auth)
>  Attempting to register auth backend netlogond
>[2011/05/16 10:24:50.956378,  5] auth/auth.c:58(smb_register_auth)
>  Successfully added auth method 'netlogond'
>[2011/05/16 10:24:50.956393,  5] auth/auth.c:383(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match guest
>[2011/05/16 10:24:50.956411,  5] auth/auth.c:408(load_auth_module)
>  load_auth_module: auth method guest has a valid init
>[2011/05/16 10:24:50.956427,  5] auth/auth.c:383(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match sam
>[2011/05/16 10:24:50.956443,  5] auth/auth.c:408(load_auth_module)
>  load_auth_module: auth method sam has a valid init
>[2011/05/16 10:24:50.956459, 10] smbd/negprot.c:49(get_challenge)
>  get challenge: getting challenge
>[2011/05/16 10:24:50.956476,  5] auth/auth.c:97(get_ntlm_challenge)
>  auth_get_challenge: module guest did not want to specify a challenge
>[2011/05/16 10:24:50.956491,  5] auth/auth.c:97(get_ntlm_challenge)
>  auth_get_challenge: module sam did not want to specify a challenge
>[2011/05/16 10:24:50.956520,  5] auth/auth.c:132(get_ntlm_challenge)
>  auth_context challenge created by random
>[2011/05/16 10:24:50.956536,  5] auth/auth.c:133(get_ntlm_challenge)
>  challenge is: 
>[2011/05/16 10:24:50.956552,  5] ../lib/util/util.c:278(_dump_data)
>  [0000] BE 23 30 57 6C EB 92 99                            .#0Wl... 
>[2011/05/16 10:24:50.956584,  3] smbd/negprot.c:385(reply_nt1)
>  not using SPNEGO
>[2011/05/16 10:24:50.956599,  3] smbd/negprot.c:691(reply_negprot)
>  Selected protocol NT LM 0.12
>[2011/05/16 10:24:50.956614,  5] smbd/negprot.c:698(reply_negprot)
>  negprot index=2
>[2011/05/16 10:24:50.956630,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:24:50.956640,  5] lib/util.c:627(show_msg)
>  size=103
>  smb_com=0x72
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=0
>  smb_pid=1064
>  smb_uid=0
>  smb_mid=436
>  smt_wct=17
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=12803 (0x3203)
>  smb_vwv[ 2]=  256 (0x100)
>  smb_vwv[ 3]= 1024 (0x400)
>  smb_vwv[ 4]=   65 (0x41)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=  256 (0x100)
>  smb_vwv[ 7]=24064 (0x5E00)
>  smb_vwv[ 8]=   38 (0x26)
>  smb_vwv[ 9]=64768 (0xFD00)
>  smb_vwv[10]=33011 (0x80F3)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=14389 (0x3835)
>  smb_vwv[13]=41657 (0xA2B9)
>  smb_vwv[14]=52243 (0xCC13)
>  smb_vwv[15]=34817 (0x8801)
>  smb_vwv[16]= 2303 (0x8FF)
>  smb_bcc=8
>[2011/05/16 10:24:50.956816, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] BE 23 30 57 6C EB 92 99                            .#0Wl... 
>[2011/05/16 10:24:50.957232, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 230
>[2011/05/16 10:24:50.957252,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0xe6
>[2011/05/16 10:24:50.957268,  3] smbd/process.c:1489(process_smb)
>  Transaction 1 of length 234 (0 toread)
>[2011/05/16 10:24:50.957284,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:24:50.957294,  5] lib/util.c:627(show_msg)
>  size=230
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=53249
>  smb_tid=0
>  smb_pid=1064
>  smb_uid=0
>  smb_mid=437
>  smt_wct=13
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=16472 (0x4058)
>  smb_vwv[ 3]=   50 (0x32)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=   24 (0x18)
>  smb_vwv[ 8]=   24 (0x18)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_vwv[10]=    0 (0x0)
>  smb_vwv[11]=53468 (0xD0DC)
>  smb_vwv[12]=  128 (0x80)
>  smb_bcc=169
>[2011/05/16 10:24:50.957427, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] EB 86 B6 FE 6D 5D 08 E9   AD 4D B7 2A FB 2B 6F 43   ....m].. .M.*.+oC
>  [0010] FA 7A 8C A3 09 F8 31 37   EB 86 B6 FE 6D 5D 08 E9   .z....17 ....m]..
>  [0020] AD 4D B7 2A FB 2B 6F 43   FA 7A 8C A3 09 F8 31 37   .M.*.+oC .z....17
>  [0030] 00 00 00 00 00 4C 00 69   00 6E 00 75 00 78 00 20   .....L.i .n.u.x. 
>  [0040] 00 76 00 65 00 72 00 73   00 69 00 6F 00 6E 00 20   .v.e.r.s .i.o.n. 
>  [0050] 00 32 00 2E 00 36 00 2E   00 33 00 32 00 2D 00 33   .2...6.. .3.2.-.3
>  [0060] 00 31 00 2D 00 67 00 65   00 6E 00 65 00 72 00 69   .1.-.g.e .n.e.r.i
>  [0070] 00 63 00 00 00 43 00 49   00 46 00 53 00 20 00 56   .c...C.I .F.S. .V
>  [0080] 00 46 00 53 00 20 00 43   00 6C 00 69 00 65 00 6E   .F.S. .C .l.i.e.n
>  [0090] 00 74 00 20 00 66 00 6F   00 72 00 20 00 4C 00 69   .t. .f.o .r. .L.i
>  [00A0] 00 6E 00 75 00 78 00 00   00                       .n.u.x.. .
>[2011/05/16 10:24:50.957678,  3] smbd/process.c:1298(switch_message)
>  switch message SMBsesssetupX (pid 9822) conn 0x0
>[2011/05/16 10:24:50.957706,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.957723,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.957739,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.957763,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2011/05/16 10:24:50.957782,  3] smbd/sesssetup.c:1436(reply_sesssetup_and_X)
>  wct=13 flg2=0xd001
>[2011/05/16 10:24:50.957802,  3] smbd/sesssetup.c:1639(reply_sesssetup_and_X)
>  Domain=[]  NativeOS=[Linux version 2.6.32-31-generic] NativeLanMan=[CIFS VFS Client for Linux] PrimaryDomain=[]
>[2011/05/16 10:24:50.957821,  2] smbd/sesssetup.c:1391(setup_new_vc_session)
>  setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources.
>[2011/05/16 10:24:50.957839,  3] smbd/sesssetup.c:1655(reply_sesssetup_and_X)
>  sesssetupX:name=[]\[]@[__ffff_192.168.0.15]
>[2011/05/16 10:24:50.957867,  6] param/loadparm.c:7133(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Mon May 16 10:21:46 2011
>  
>[2011/05/16 10:24:50.957900,  3] smbd/sesssetup.c:140(check_guest_password)
>  Got anonymous request
>[2011/05/16 10:24:50.957916,  5] auth/auth.c:487(make_auth_context_subsystem)
>  Making default auth method list for standalone security=user, encrypt passwords = yes
>[2011/05/16 10:24:50.957935,  5] auth/auth.c:383(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match guest
>[2011/05/16 10:24:50.957951,  5] auth/auth.c:408(load_auth_module)
>  load_auth_module: auth method guest has a valid init
>[2011/05/16 10:24:50.957976,  5] auth/auth.c:383(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match sam
>[2011/05/16 10:24:50.957994,  5] auth/auth.c:408(load_auth_module)
>  load_auth_module: auth method sam has a valid init
>[2011/05/16 10:24:50.958011,  5] auth/auth_util.c:122(make_user_info)
>  attempting to make a user_info for  ()
>[2011/05/16 10:24:50.958029,  5] auth/auth_util.c:132(make_user_info)
>  making strings for 's user_info struct
>[2011/05/16 10:24:50.958045,  5] auth/auth_util.c:164(make_user_info)
>  making blobs for 's user_info struct
>[2011/05/16 10:24:50.958060, 10] auth/auth_util.c:182(make_user_info)
>  made an encrypted user_info for  ()
>[2011/05/16 10:24:50.958076,  3] auth/auth.c:216(check_ntlm_password)
>  check_ntlm_password:  Checking password for unmapped user []\[]@[] with the new password interface
>[2011/05/16 10:24:50.958093,  3] auth/auth.c:219(check_ntlm_password)
>  check_ntlm_password:  mapped user is: []\[]@[]
>[2011/05/16 10:24:50.958109, 10] auth/auth.c:228(check_ntlm_password)
>  check_ntlm_password: auth_context challenge created by fixed
>[2011/05/16 10:24:50.958125, 10] auth/auth.c:230(check_ntlm_password)
>  challenge is: 
>[2011/05/16 10:24:50.958139,  5] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 00 00 00 00 00 00                            ........ 
>[2011/05/16 10:24:50.958249,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.958268,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.958284,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.958300,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.958315,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.958352, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2011/05/16 10:24:50.958373,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.958403, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username office, was 
>[2011/05/16 10:24:50.958420, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain OFFICE, was 
>[2011/05/16 10:24:50.958437, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username , was 
>[2011/05/16 10:24:50.958453, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2011/05/16 10:24:50.958471,  4] lib/substitute.c:504(automount_server)
>  Home server: office
>[2011/05/16 10:24:50.958491, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir \\office\office, was 
>[2011/05/16 10:24:50.958508, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2011/05/16 10:24:50.958527, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2011/05/16 10:24:50.958544,  4] lib/substitute.c:504(automount_server)
>  Home server: office
>[2011/05/16 10:24:50.958563, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path \\office\office\profile, was 
>[2011/05/16 10:24:50.958580, 10] passdb/pdb_get_set.c:813(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2011/05/16 10:24:50.958597,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.958614,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.958630,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.958646,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.958661,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.958711, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2011/05/16 10:24:50.958731,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.958751, 10] passdb/pdb_get_set.c:537(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-3937548381-614798192-88495934-501
>[2011/05/16 10:24:50.958772, 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-3937548381-614798192-88495934-501 from rid 501
>[2011/05/16 10:24:50.958818, 10] lib/gencache.c:334(gencache_get_data_blob)
>  Cache entry with key = IDMAP/SID2GID/S-1-5-21-3937548381-614798192-88495934-513 couldn't be found 
>[2011/05/16 10:24:50.958888, 10] passdb/lookup_sid.c:1511(sid_to_gid)
>  winbind failed to find a gid for sid S-1-5-21-3937548381-614798192-88495934-513
>[2011/05/16 10:24:50.958912,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.958928,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.958945,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.958961,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.958976,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.959004,  5] passdb/pdb_interface.c:1473(lookup_global_sam_rid)
>  lookup_global_sam_rid: looking up RID 513.
>[2011/05/16 10:24:50.959021,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2011/05/16 10:24:50.959038,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.959053,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2011/05/16 10:24:50.959069,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.959085,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.959121,  5] passdb/pdb_tdb.c:609(tdbsam_getsampwrid)
>  pdb_getsampwrid (TDB): error looking up RID 513 by key RID_00000201.
>[2011/05/16 10:24:50.959182,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.959201,  5] passdb/pdb_interface.c:1536(lookup_global_sam_rid)
>  Can't find a unix id for an unmapped group
>[2011/05/16 10:24:50.959220,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.959237, 10] passdb/lookup_sid.c:1283(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-3937548381-614798192-88495934-513
>[2011/05/16 10:24:50.959255, 10] passdb/pdb_get_set.c:595(pdb_set_group_sid)
>  pdb_set_group_sid: setting group sid S-1-5-21-3937548381-614798192-88495934-513
>[2011/05/16 10:24:50.959274,  3] auth/auth.c:265(check_ntlm_password)
>  check_ntlm_password: guest authentication for user [] succeeded
>[2011/05/16 10:24:50.959291,  5] auth/auth.c:304(check_ntlm_password)
>  check_ntlm_password:  guest authentication for user [] -> [] -> [office] succeeded
>[2011/05/16 10:24:50.959309,  5] auth/auth_util.c:2119(free_user_info)
>  attempting to free (and zero) a user_info structure
>[2011/05/16 10:24:50.959325, 10] auth/auth_util.c:2123(free_user_info)
>  structure was created for 
>[2011/05/16 10:24:50.959341,  5] auth/auth_util.c:2119(free_user_info)
>  attempting to free (and zero) a user_info structure
>[2011/05/16 10:24:50.959363, 10] smbd/password.c:184(register_initial_vuid)
>  register_initial_vuid: allocated vuid = 100
>[2011/05/16 10:24:50.959381, 10] smbd/password.c:278(register_existing_vuid)
>  register_existing_vuid: (1019,100) office office OFFICE guest=1
>[2011/05/16 10:24:50.959398,  3] smbd/password.c:282(register_existing_vuid)
>  register_existing_vuid: User name: office	Real name: 
>[2011/05/16 10:24:50.959427,  3] smbd/password.c:292(register_existing_vuid)
>  register_existing_vuid: UNIX uid 1019 is UNIX user office, and will be vuid 100
>[2011/05/16 10:24:50.959459,  6] param/loadparm.c:7133(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Mon May 16 10:21:46 2011
>  
>[2011/05/16 10:24:50.959692, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 98
>[2011/05/16 10:24:50.959726,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x62
>[2011/05/16 10:24:50.959742,  3] smbd/process.c:1489(process_smb)
>  Transaction 2 of length 102 (0 toread)
>[2011/05/16 10:24:50.959758,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:24:50.959768,  5] lib/util.c:627(show_msg)
>  size=98
>  smb_com=0x75
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=53249
>  smb_tid=0
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=438
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    8 (0x8)
>  smb_vwv[ 3]=    1 (0x1)
>  smb_bcc=55
>[2011/05/16 10:24:50.959852, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 5C 00 5C 00 31 00 39   00 32 00 2E 00 31 00 36   .\.\.1.9 .2...1.6
>  [0010] 00 38 00 2E 00 30 00 2E   00 31 00 5C 00 67 00 65   .8...0.. .1.\.g.e
>  [0020] 00 6D 00 65 00 69 00 6E   00 73 00 61 00 6D 00 00   .m.e.i.n .s.a.m..
>  [0030] 00 3F 3F 3F 3F 3F 00                              .?????. 
>[2011/05/16 10:24:50.959939,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtconX (pid 9822) conn 0x0
>[2011/05/16 10:24:50.959957,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.959974,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.959989,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.960012,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2011/05/16 10:24:50.960035,  4] smbd/reply.c:786(reply_tcon_and_X)
>  Client requested device type [?????] for share [gemeinsam]
>[2011/05/16 10:24:50.960061,  5] smbd/service.c:1227(make_connection)
>  making a connection to 'normal' service gemeinsam
>[2011/05/16 10:24:50.960090,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.960107,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.960122,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.960138,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.960153,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.960182, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2011/05/16 10:24:50.960202,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.960222, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username office, was 
>[2011/05/16 10:24:50.960238, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain OFFICE, was 
>[2011/05/16 10:24:50.960254, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username , was 
>[2011/05/16 10:24:50.960270, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2011/05/16 10:24:50.960286,  4] lib/substitute.c:504(automount_server)
>  Home server: office
>[2011/05/16 10:24:50.960304, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir \\office\office, was 
>[2011/05/16 10:24:50.960321, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2011/05/16 10:24:50.960337, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2011/05/16 10:24:50.960363,  4] lib/substitute.c:504(automount_server)
>  Home server: office
>[2011/05/16 10:24:50.960382, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path \\office\office\profile, was 
>[2011/05/16 10:24:50.960399, 10] passdb/pdb_get_set.c:813(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2011/05/16 10:24:50.960415,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.960431,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.960447,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.960463,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.960478,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.960505, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2011/05/16 10:24:50.960526,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.960543, 10] passdb/pdb_get_set.c:537(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-3937548381-614798192-88495934-501
>[2011/05/16 10:24:50.960561, 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-3937548381-614798192-88495934-501 from rid 501
>[2011/05/16 10:24:50.960597, 10] lib/gencache.c:334(gencache_get_data_blob)
>  Cache entry with key = IDMAP/SID2GID/S-1-5-21-3937548381-614798192-88495934-513 couldn't be found 
>[2011/05/16 10:24:50.960625, 10] passdb/lookup_sid.c:1511(sid_to_gid)
>  winbind failed to find a gid for sid S-1-5-21-3937548381-614798192-88495934-513
>[2011/05/16 10:24:50.960644,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.960661,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.960676,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.960692,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.960722,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.960746,  5] passdb/pdb_interface.c:1473(lookup_global_sam_rid)
>  lookup_global_sam_rid: looking up RID 513.
>[2011/05/16 10:24:50.960763,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2011/05/16 10:24:50.960779,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.960794,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2011/05/16 10:24:50.960810,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.960826,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.960853,  5] passdb/pdb_tdb.c:609(tdbsam_getsampwrid)
>  pdb_getsampwrid (TDB): error looking up RID 513 by key RID_00000201.
>[2011/05/16 10:24:50.960905,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.960922,  5] passdb/pdb_interface.c:1536(lookup_global_sam_rid)
>  Can't find a unix id for an unmapped group
>[2011/05/16 10:24:50.960941,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.960958, 10] passdb/lookup_sid.c:1283(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-3937548381-614798192-88495934-513
>[2011/05/16 10:24:50.960976, 10] passdb/pdb_get_set.c:595(pdb_set_group_sid)
>  pdb_set_group_sid: setting group sid S-1-5-21-3937548381-614798192-88495934-513
>[2011/05/16 10:24:50.960996,  5] lib/username.c:133(Get_Pwnam_alloc)
>  Finding user office
>[2011/05/16 10:24:50.961021,  5] lib/username.c:77(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is office
>[2011/05/16 10:24:50.961038,  5] lib/username.c:110(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [office]!
>[2011/05/16 10:24:50.961067, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service gemeinsam, connectpath = /home/alle
>[2011/05/16 10:24:50.961084,  3] smbd/service.c:807(make_connection_snum)
>  Connect path is '/home/alle' for service [gemeinsam]
>[2011/05/16 10:24:50.961112, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2011/05/16 10:24:50.961133,  3] smbd/vfs.c:97(vfs_init_default)
>  Initialising default vfs hooks
>[2011/05/16 10:24:50.961150, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for /[Default VFS]/
>[2011/05/16 10:24:50.961166,  5] smbd/vfs.c:87(smb_register_vfs)
>  Successfully added vfs backend '/[Default VFS]/'
>[2011/05/16 10:24:50.961183, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for posixacl
>[2011/05/16 10:24:50.961198,  5] smbd/vfs.c:87(smb_register_vfs)
>  Successfully added vfs backend 'posixacl'
>[2011/05/16 10:24:50.961213,  3] smbd/vfs.c:122(vfs_init_custom)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2011/05/16 10:24:50.961229, 10] smbd/vfs.c:48(vfs_find_backend_entry)
>  vfs_find_backend_entry called for /[Default VFS]/
>  Successfully loaded vfs module [/[Default VFS]/] with the new modules system
>[2011/05/16 10:24:50.961327,  5] smbd/connection.c:142(claim_connection)
>  claiming [gemeinsam]
>[2011/05/16 10:24:50.961429, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 5E260000010000006765
>[2011/05/16 10:24:50.961450, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0xb7808200
>[2011/05/16 10:24:50.961475, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 5E260000010000006765
>[2011/05/16 10:24:50.961587, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service gemeinsam, connectpath = /home/alle
>[2011/05/16 10:24:50.961608, 10] smbd/share_access.c:238(user_ok_token)
>  user_ok_token: share gemeinsam is ok for unix user office
>[2011/05/16 10:24:50.961626, 10] smbd/share_access.c:283(is_share_read_only_for_token)
>  is_share_read_only_for_user: share gemeinsam is read-write for unix user office
>[2011/05/16 10:24:50.961649, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2011/05/16 10:24:50.961671, 10] lib/util_seaccess.c:58(se_map_generic)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2011/05/16 10:24:50.961693,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.961721,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.961736,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.961752,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.961767,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.961795, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2011/05/16 10:24:50.961814,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.961835, 10] passdb/pdb_get_set.c:608(pdb_set_username)
>  pdb_set_username: setting username office, was 
>[2011/05/16 10:24:50.961851, 10] passdb/pdb_get_set.c:631(pdb_set_domain)
>  pdb_set_domain: setting domain OFFICE, was 
>[2011/05/16 10:24:50.961866, 10] passdb/pdb_get_set.c:654(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username , was 
>[2011/05/16 10:24:50.961882, 10] passdb/pdb_get_set.c:677(pdb_set_fullname)
>  pdb_set_full_name: setting full name , was 
>[2011/05/16 10:24:50.961898,  4] lib/substitute.c:504(automount_server)
>  Home server: office
>[2011/05/16 10:24:50.961927, 10] passdb/pdb_get_set.c:770(pdb_set_homedir)
>  pdb_set_homedir: setting home dir \\office\office, was 
>[2011/05/16 10:24:50.961943, 10] passdb/pdb_get_set.c:746(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2011/05/16 10:24:50.961960, 10] passdb/pdb_get_set.c:700(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2011/05/16 10:24:50.961976,  4] lib/substitute.c:504(automount_server)
>  Home server: office
>[2011/05/16 10:24:50.961994, 10] passdb/pdb_get_set.c:723(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path \\office\office\profile, was 
>[2011/05/16 10:24:50.962011, 10] passdb/pdb_get_set.c:813(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2011/05/16 10:24:50.962028,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.962044,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.962059,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.962075,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.962090,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.962118, 10] lib/account_pol.c:333(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2011/05/16 10:24:50.962137,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.962154, 10] passdb/pdb_get_set.c:537(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-3937548381-614798192-88495934-501
>[2011/05/16 10:24:50.962173, 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-3937548381-614798192-88495934-501 from rid 501
>[2011/05/16 10:24:50.962208, 10] lib/gencache.c:334(gencache_get_data_blob)
>  Cache entry with key = IDMAP/SID2GID/S-1-5-21-3937548381-614798192-88495934-513 couldn't be found 
>[2011/05/16 10:24:50.962237, 10] passdb/lookup_sid.c:1511(sid_to_gid)
>  winbind failed to find a gid for sid S-1-5-21-3937548381-614798192-88495934-513
>[2011/05/16 10:24:50.962256,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.962272,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.962288,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.962303,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.962319,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.962343,  5] passdb/pdb_interface.c:1473(lookup_global_sam_rid)
>  lookup_global_sam_rid: looking up RID 513.
>[2011/05/16 10:24:50.962360,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2011/05/16 10:24:50.962376,  3] smbd/uid.c:429(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.962391,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2011/05/16 10:24:50.962407,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.962422,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.962451,  5] passdb/pdb_tdb.c:609(tdbsam_getsampwrid)
>  pdb_getsampwrid (TDB): error looking up RID 513 by key RID_00000201.
>[2011/05/16 10:24:50.962503,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2011/05/16 10:24:50.962521,  5] passdb/pdb_interface.c:1536(lookup_global_sam_rid)
>  Can't find a unix id for an unmapped group
>[2011/05/16 10:24:50.962539,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.962565, 10] passdb/lookup_sid.c:1283(legacy_sid_to_gid)
>  LEGACY: mapping failed for sid S-1-5-21-3937548381-614798192-88495934-513
>[2011/05/16 10:24:50.962584, 10] passdb/pdb_get_set.c:595(pdb_set_group_sid)
>  pdb_set_group_sid: setting group sid S-1-5-21-3937548381-614798192-88495934-513
>[2011/05/16 10:24:50.962607,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (1019, 100) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.962624,  5] auth/token_util.c:531(debug_nt_user_token)
>  NT user token of user S-1-5-21-3937548381-614798192-88495934-501
>  contains 6 SIDs
>  SID[  0]: S-1-5-21-3937548381-614798192-88495934-501
>  SID[  1]: S-1-22-2-100
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-32-546
>  SID[  5]: S-1-22-1-1019
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2011/05/16 10:24:50.962693,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 1019
>  Primary group is 100 and contains 1 supplementary groups
>  Group[  0]: 100
>[2011/05/16 10:24:50.962737,  5] smbd/uid.c:354(change_to_user)
>  change_to_user uid=(0,1019) gid=(0,100)
>[2011/05/16 10:24:50.962768, 10] smbd/service.c:163(set_conn_connectpath)
>  set_conn_connectpath: service gemeinsam, connectpath = /home/alle
>[2011/05/16 10:24:50.962796,  1] smbd/service.c:1070(make_connection_snum)
>  __ffff_192.168.0.15 (::ffff:192.168.0.15) connect to service gemeinsam initially as user office (uid=1019, gid=100) (pid 9822)
>[2011/05/16 10:24:50.962818,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.962834,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:24:50.962849,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:24:50.962875,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2011/05/16 10:24:50.962896,  3] smbd/reply.c:865(reply_tcon_and_X)
>  tconX service=gemeinsam 
>[2011/05/16 10:24:50.963049, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 68
>[2011/05/16 10:24:50.963070,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x44
>[2011/05/16 10:24:50.963086,  3] smbd/process.c:1489(process_smb)
>  Transaction 3 of length 72 (0 toread)
>[2011/05/16 10:24:50.963101,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:24:50.963112,  5] lib/util.c:627(show_msg)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=439
>  smt_wct=15
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=  100 (0x64)
>  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]=    2 (0x2)
>  smb_vwv[10]=   66 (0x42)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    3 (0x3)
>  smb_bcc=3
>[2011/05/16 10:24:50.963248, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 02                                          ... 
>[2011/05/16 10:24:50.963272,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:24:50.963290,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (1019, 100) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:24:50.963306,  5] auth/token_util.c:531(debug_nt_user_token)
>  NT user token of user S-1-5-21-3937548381-614798192-88495934-501
>  contains 6 SIDs
>  SID[  0]: S-1-5-21-3937548381-614798192-88495934-501
>  SID[  1]: S-1-22-2-100
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-32-546
>  SID[  5]: S-1-22-1-1019
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2011/05/16 10:24:50.963373,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 1019
>  Primary group is 100 and contains 1 supplementary groups
>  Group[  0]: 100
>[2011/05/16 10:24:50.963402,  5] smbd/uid.c:354(change_to_user)
>  change_to_user uid=(0,1019) gid=(0,100)
>[2011/05/16 10:24:50.963423,  4] smbd/vfs.c:751(vfs_ChDir)
>  vfs_ChDir to /home/alle
>[2011/05/16 10:24:50.963470,  3] smbd/trans2.c:3463(call_trans2qfsinfo)
>  call_trans2qfsinfo: level = 512
>[2011/05/16 10:24:50.963492,  3] smbd/trans2.c:2906(smbd_do_qfsinfo)
>  smbd_do_qfsinfo: level = 512
>[2011/05/16 10:24:50.963517,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 0, data_sent_thistime = 12, useable_space = 16412
>[2011/05/16 10:24:50.963535,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 0, data_to_send = 12, paramsize = 0, datasize = 12
>[2011/05/16 10:24:50.963551,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:24:50.963561,  5] lib/util.c:627(show_msg)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=439
>  smt_wct=10
>  smb_vwv[ 0]=    0 (0x0)
>  smb_vwv[ 1]=   12 (0xC)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    0 (0x0)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   12 (0xC)
>  smb_vwv[ 7]=   56 (0x38)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=13
>[2011/05/16 10:24:50.963680, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 01 00 00 00 7B 01 00   00 00 00 00 00           .....{.. .....
>[2011/05/16 10:24:50.963730,  4] smbd/trans2.c:3479(call_trans2qfsinfo)
>  SMBtrans2 info_level = 512
>[2011/05/16 10:24:50.963846, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 82
>[2011/05/16 10:24:50.963866,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x52
>[2011/05/16 10:24:50.963882,  3] smbd/process.c:1489(process_smb)
>  Transaction 4 of length 86 (0 toread)
>[2011/05/16 10:24:50.963898,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:24:50.963908,  5] lib/util.c:627(show_msg)
>  size=82
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=440
>  smt_wct=15
>  smb_vwv[ 0]=    4 (0x4)
>  smb_vwv[ 1]=   12 (0xC)
>  smb_vwv[ 2]=    4 (0x4)
>  smb_vwv[ 3]=  100 (0x64)
>  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]=   66 (0x42)
>  smb_vwv[11]=   12 (0xC)
>  smb_vwv[12]=   70 (0x46)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    4 (0x4)
>  smb_bcc=17
>[2011/05/16 10:24:50.964054, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 00 00 02 01 00 00   00 5B 00 00 00 00 00 00   ........ .[......
>  [0010] 00                                                . 
>[2011/05/16 10:24:50.964097,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:24:50.964113,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:24:50.964130, 10] smbd/trans2.c:3498(call_trans2setfsinfo)
>  call_trans2setfsinfo: for service [gemeinsam]
>[2011/05/16 10:24:50.964147, 10] smbd/trans2.c:3563(call_trans2setfsinfo)
>  call_trans2setfsinfo: set unix info. major = 1, minor = 0 cap_low = 0x5b, cap_high = 0x0
>[2011/05/16 10:24:50.964171,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:24:50.964182,  5] lib/util.c:627(show_msg)
>  size=55
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=440
>  smt_wct=10
>  smb_vwv[ 0]=    0 (0x0)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    0 (0x0)
>  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_bcc=0
>[2011/05/16 10:24:50.964419, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 68
>[2011/05/16 10:24:50.964439,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x44
>[2011/05/16 10:24:50.964454,  3] smbd/process.c:1489(process_smb)
>  Transaction 5 of length 72 (0 toread)
>[2011/05/16 10:24:50.964470,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:24:50.964480,  5] lib/util.c:627(show_msg)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=441
>  smt_wct=15
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=  100 (0x64)
>  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]=    2 (0x2)
>  smb_vwv[10]=   66 (0x42)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    3 (0x3)
>  smb_bcc=3
>[2011/05/16 10:24:50.964628, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 01 02                                          ... 
>[2011/05/16 10:24:50.964652,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:24:50.964668,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:24:50.964684,  3] smbd/trans2.c:3463(call_trans2qfsinfo)
>  call_trans2qfsinfo: level = 513
>[2011/05/16 10:24:50.964720,  3] smbd/trans2.c:2906(smbd_do_qfsinfo)
>  smbd_do_qfsinfo: level = 513
>[2011/05/16 10:24:50.964928,  5] smbd/trans2.c:3286(smbd_do_qfsinfo)
>  smbd_do_qfsinfo : SMB_QUERY_POSIX_FS_INFO succsessful
>[2011/05/16 10:24:50.964948,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 0, data_sent_thistime = 56, useable_space = 16412
>[2011/05/16 10:24:50.964964,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 0, data_to_send = 56, paramsize = 0, datasize = 56
>[2011/05/16 10:24:50.964980,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:24:50.964990,  5] lib/util.c:627(show_msg)
>  size=112
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=441
>  smt_wct=10
>  smb_vwv[ 0]=    0 (0x0)
>  smb_vwv[ 1]=   56 (0x38)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    0 (0x0)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   56 (0x38)
>  smb_vwv[ 7]=   56 (0x38)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=57
>[2011/05/16 10:24:50.965105, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 10 00 00 00 10 00   00 82 92 53 0E 00 00 00   ........ ...S....
>  [0010] 00 F4 2E 3D 08 00 00 00   00 98 E1 82 07 00 00 00   ...=.... ........
>  [0020] 00 00 A0 A3 03 00 00 00   00 63 9E 97 03 00 00 00   ........ .c......
>  [0030] 00 A9 39 2C 52 00 00 00   00                       ..9,R... .
>[2011/05/16 10:24:50.965204,  4] smbd/trans2.c:3479(call_trans2qfsinfo)
>  SMBtrans2 info_level = 513
>[2011/05/16 10:24:50.965337, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 74
>[2011/05/16 10:24:50.965357,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x4a
>[2011/05/16 10:24:50.965373,  3] smbd/process.c:1489(process_smb)
>  Transaction 6 of length 78 (0 toread)
>[2011/05/16 10:24:50.965389,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:24:50.965399,  5] lib/util.c:627(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=1064
>  smb_uid=100
>  smb_mid=442
>  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
>[2011/05/16 10:24:50.965538, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 02 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:24:50.965565,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:24:50.965581,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:24:50.965599,  3] smbd/trans2.c:5099(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
>[2011/05/16 10:24:50.965626,  5] smbd/filename.c:169(unix_convert)
>  unix_convert called on file ""
>[2011/05/16 10:24:50.965662,  5] smbd/filename.c:203(unix_convert)
>  conversion finished "" -> .
>[2011/05/16 10:24:50.965679,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [.] [/home/alle]
>[2011/05/16 10:24:50.965709, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/alle]
>[2011/05/16 10:24:50.965727,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: . reduced to /home/alle
>[2011/05/16 10:24:50.965761, 10] locking/locking.c:926(fetch_share_mode_unlocked)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2011/05/16 10:24:50.965779,  3] smbd/trans2.c:5225(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0
>[2011/05/16 10:24:50.965797,  5] smbd/trans2.c:4197(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: . (fnum = -1) level=512 max_data=4000
>[2011/05/16 10:24:50.965815,  8] smbd/dosmode.c:613(dos_mode)
>  dos_mode: .
>[2011/05/16 10:24:50.965833,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning d
>[2011/05/16 10:24:50.965852,  8] smbd/dosmode.c:666(dos_mode)
>  dos_mode returning d
>[2011/05/16 10:24:50.965875, 10] smbd/trans2.c:3873(store_file_unix_basic)
>  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
>[2011/05/16 10:24:50.965890,  4] smbd/trans2.c:3874(store_file_unix_basic)
>  store_file_unix_basic: st_mode=40777
>[2011/05/16 10:24:50.965909,  4] smbd/trans2.c:4724(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: 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=0, 17=38, 18=fffffff4, 19=ffffffa4, 20=ffffffa2, 21=13, 22=ffffffcc, 23=1, 24=0, 25=ffffffd5, 26=ffffffa9, 27=1a, 28=ffffff9e, 29=13, 30=ffffffcc, 31=1, 32=0, 33=38, 34=fffffff4, 35=ffffffa4, 36=ffffffa2, 37=13, 38=ffffffcc, 39=1, 40=fffffffb, 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=5e, 77=ffffffa2, 78=ffffffae, 79=2, 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=f, 93=0, 94=0, 95=0, 96=0, 97=0, 98=0, 99=0, 
>[2011/05/16 10:24:50.965999,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 100, useable_space = 16410
>[2011/05/16 10:24:50.966015,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 100, paramsize = 2, datasize = 100
>[2011/05/16 10:24:50.966031,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:24:50.966042,  5] lib/util.c:627(show_msg)
>  size=160
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=442
>  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
>[2011/05/16 10:24:50.966157, 10] ../lib/util/util.c:278(_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 00 38 F4   A4 A2 13 CC 01 00 D5 A9   ......8. ........
>  [0020] 1A 9E 13 CC 01 00 38 F4   A4 A2 13 CC 01 FB 03 00   ......8. ........
>  [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 5E A2 AE 02 00 00 00   00 FF 01 00 00 00 00 00   .^...... ........
>  [0060] 00 0F 00 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:00.954627, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 68
>[2011/05/16 10:25:00.954668,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x44
>[2011/05/16 10:25:00.954687,  3] smbd/process.c:1489(process_smb)
>  Transaction 7 of length 72 (0 toread)
>[2011/05/16 10:25:00.954716,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:00.954746,  5] lib/util.c:627(show_msg)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=443
>  smt_wct=15
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=  100 (0x64)
>  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]=    2 (0x2)
>  smb_vwv[10]=   66 (0x42)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    3 (0x3)
>  smb_bcc=3
>[2011/05/16 10:25:00.954887, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 01 02                                          ... 
>[2011/05/16 10:25:00.954915,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:00.954933,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:00.954953,  3] smbd/trans2.c:3463(call_trans2qfsinfo)
>  call_trans2qfsinfo: level = 513
>[2011/05/16 10:25:00.954976,  3] smbd/trans2.c:2906(smbd_do_qfsinfo)
>  smbd_do_qfsinfo: level = 513
>[2011/05/16 10:25:00.955154,  5] smbd/trans2.c:3286(smbd_do_qfsinfo)
>  smbd_do_qfsinfo : SMB_QUERY_POSIX_FS_INFO succsessful
>[2011/05/16 10:25:00.955175,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 0, data_sent_thistime = 56, useable_space = 16412
>[2011/05/16 10:25:00.955191,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 0, data_to_send = 56, paramsize = 0, datasize = 56
>[2011/05/16 10:25:00.955208,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:00.955218,  5] lib/util.c:627(show_msg)
>  size=112
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=443
>  smt_wct=10
>  smb_vwv[ 0]=    0 (0x0)
>  smb_vwv[ 1]=   56 (0x38)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    0 (0x0)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   56 (0x38)
>  smb_vwv[ 7]=   56 (0x38)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=57
>[2011/05/16 10:25:00.955333, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 10 00 00 00 10 00   00 82 92 53 0E 00 00 00   ........ ...S....
>  [0010] 00 F4 2E 3D 08 00 00 00   00 98 E1 82 07 00 00 00   ...=.... ........
>  [0020] 00 00 A0 A3 03 00 00 00   00 63 9E 97 03 00 00 00   ........ .c......
>  [0030] 00 A9 39 2C 52 00 00 00   00                       ..9,R... .
>[2011/05/16 10:25:00.955435,  4] smbd/trans2.c:3479(call_trans2qfsinfo)
>  SMBtrans2 info_level = 513
>[2011/05/16 10:25:00.955746, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 74
>[2011/05/16 10:25:00.955766,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x4a
>[2011/05/16 10:25:00.955782,  3] smbd/process.c:1489(process_smb)
>  Transaction 8 of length 78 (0 toread)
>[2011/05/16 10:25:00.955798,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:00.955809,  5] lib/util.c:627(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=1064
>  smb_uid=100
>  smb_mid=444
>  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
>[2011/05/16 10:25:00.955948, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 02 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:00.955976,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:00.955993,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:00.956009,  3] smbd/trans2.c:5099(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
>[2011/05/16 10:25:00.956042,  5] smbd/filename.c:169(unix_convert)
>  unix_convert called on file ""
>[2011/05/16 10:25:00.956060,  5] smbd/filename.c:203(unix_convert)
>  conversion finished "" -> .
>[2011/05/16 10:25:00.956076,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [.] [/home/alle]
>[2011/05/16 10:25:00.956095, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/alle]
>[2011/05/16 10:25:00.956112,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: . reduced to /home/alle
>[2011/05/16 10:25:00.956139, 10] locking/locking.c:926(fetch_share_mode_unlocked)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2011/05/16 10:25:00.956155,  3] smbd/trans2.c:5225(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0
>[2011/05/16 10:25:00.956172,  5] smbd/trans2.c:4197(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: . (fnum = -1) level=512 max_data=4000
>[2011/05/16 10:25:00.956189,  8] smbd/dosmode.c:613(dos_mode)
>  dos_mode: .
>[2011/05/16 10:25:00.956207,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning d
>[2011/05/16 10:25:00.956223,  8] smbd/dosmode.c:666(dos_mode)
>  dos_mode returning d
>[2011/05/16 10:25:00.956243, 10] smbd/trans2.c:3873(store_file_unix_basic)
>  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
>[2011/05/16 10:25:00.956258,  4] smbd/trans2.c:3874(store_file_unix_basic)
>  store_file_unix_basic: st_mode=40777
>[2011/05/16 10:25:00.956274,  4] smbd/trans2.c:4724(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: 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=0, 17=38, 18=fffffff4, 19=ffffffa4, 20=ffffffa2, 21=13, 22=ffffffcc, 23=1, 24=0, 25=ffffffd5, 26=ffffffa9, 27=1a, 28=ffffff9e, 29=13, 30=ffffffcc, 31=1, 32=0, 33=38, 34=fffffff4, 35=ffffffa4, 36=ffffffa2, 37=13, 38=ffffffcc, 39=1, 40=fffffffb, 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=5e, 77=ffffffa2, 78=ffffffae, 79=2, 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=f, 93=0, 94=0, 95=0, 96=0, 97=0, 98=0, 99=0, 
>[2011/05/16 10:25:00.956365,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 100, useable_space = 16410
>[2011/05/16 10:25:00.956381,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 100, paramsize = 2, datasize = 100
>[2011/05/16 10:25:00.956396,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:00.956407,  5] lib/util.c:627(show_msg)
>  size=160
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=444
>  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
>[2011/05/16 10:25:00.956517, 10] ../lib/util/util.c:278(_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 00 38 F4   A4 A2 13 CC 01 00 D5 A9   ......8. ........
>  [0020] 1A 9E 13 CC 01 00 38 F4   A4 A2 13 CC 01 FB 03 00   ......8. ........
>  [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 5E A2 AE 02 00 00 00   00 FF 01 00 00 00 00 00   .^...... ........
>  [0060] 00 0F 00 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:09.658275, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 106
>[2011/05/16 10:25:09.658320,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x6a
>[2011/05/16 10:25:09.658362,  3] smbd/process.c:1489(process_smb)
>  Transaction 9 of length 110 (0 toread)
>[2011/05/16 10:25:09.658380,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:09.658392,  5] lib/util.c:627(show_msg)
>  size=106
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=2587
>  smb_uid=100
>  smb_mid=445
>  smt_wct=15
>  smb_vwv[ 0]=   20 (0x14)
>  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]=   20 (0x14)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=   18 (0x12)
>  smb_vwv[12]=   88 (0x58)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    6 (0x6)
>  smb_bcc=41
>[2011/05/16 10:25:09.658558, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 00 09 02 00 00 00   00 2F 00 74 00 65 00 73   ........ ./.t.e.s
>  [0010] 00 74 00 37 00 00 00 00   00 00 00 12 00 00 00 A4   .t.7.... ........
>  [0020] 01 00 00 00 00 00 00 00   02                       ........ .
>[2011/05/16 10:25:09.658635,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:09.658654,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:09.658683,  5] smbd/filename.c:169(unix_convert)
>  unix_convert called on file "test7"
>[2011/05/16 10:25:09.658803,  5] smbd/filename.c:328(unix_convert)
>  unix_convert begin: name = test7, dirpath = , start = test7
>[2011/05/16 10:25:09.658856,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [test7] [/home/alle]
>[2011/05/16 10:25:09.658892, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [test7] -> [/home/alle/test7]
>[2011/05/16 10:25:09.658911,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: test7 reduced to /home/alle/test7
>[2011/05/16 10:25:09.658930,  3] smbd/trans2.c:7722(call_trans2setfilepathinfo)
>  call_trans2setfilepathinfo(6) test7 (fnum -1) info_level=521 totdata=18
>[2011/05/16 10:25:09.658951,  3] smbd/trans2.c:7320(smbd_do_setfilepathinfo)
>  smbd_do_setfilepathinfo: test7 (fnum -1) info_level=521 totdata=18
>[2011/05/16 10:25:09.658972, 10] smbd/trans2.c:7088(smb_posix_open)
>  smb_posix_open: file test7, smb_posix_flags = 16, mode 0666
>[2011/05/16 10:25:09.658993, 10] smbd/open.c:3381(create_file_default)
>  create_file: access_mask = 0x2 file_attributes = 0x10001b6, share_access = 0x7, create_disposition = 0x3 create_options = 0x40 oplock_request = 0x0 root_dir_fid = 0x0, ea_list = 0x(nil), sd = 0x(nil), fname = test7
>[2011/05/16 10:25:09.659018,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [test7] [/home/alle]
>[2011/05/16 10:25:09.659044, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [test7] -> [/home/alle/test7]
>[2011/05/16 10:25:09.659061,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: test7 reduced to /home/alle/test7
>[2011/05/16 10:25:09.659080, 10] smbd/open.c:2906(create_file_unixpath)
>  create_file_unixpath: access_mask = 0x2 file_attributes = 0x10001b6, share_access = 0x7, create_disposition = 0x3 create_options = 0x40 oplock_request = 0x0 ea_list = 0x(nil), sd = 0x(nil), fname = test7
>[2011/05/16 10:25:09.659108,  5] smbd/files.c:119(file_new)
>  allocated file structure 14651, fnum = 18747 (1 used)
>[2011/05/16 10:25:09.659131, 10] smbd/open.c:1533(open_file_ntcreate)
>  open_file_ntcreate: fname=test7, dos_attrs=0x0 access_mask=0x2 share_access=0x7 create_disposition = 0x3 create_options=0x40 unix mode=0646 oplock_request=0
>[2011/05/16 10:25:09.659152,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [test7] [/home/alle]
>[2011/05/16 10:25:09.659178, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [test7] -> [/home/alle/test7]
>[2011/05/16 10:25:09.659195,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: test7 reduced to /home/alle/test7
>[2011/05/16 10:25:09.659214, 10] smbd/open.c:1715(open_file_ntcreate)
>  open_file_ntcreate: fname=test7, after mapping access_mask=0x2
>[2011/05/16 10:25:09.659246,  4] smbd/open.c:1977(open_file_ntcreate)
>  calling open_file with flags=0x1 flags2=0x40 mode=0646, access_mask = 0x2, open_access_mask = 0x2
>[2011/05/16 10:25:09.659285, 10] smbd/open.c:170(fd_open)
>  fd_open: name test7, flags = 0400101 mode = 0646, fd = 32. 
>[2011/05/16 10:25:09.659322, 10] smbd/notify_internal.c:890(notify_trigger)
>  notify_trigger called action=0x1, filter=0x1, path=/home/alle/test7
>[2011/05/16 10:25:09.659347, 10] smbd/notify_internal.c:189(notify_load)
>  notify_load:
>[2011/05/16 10:25:09.659365,  1] ../librpc/ndr/ndr.c:214(ndr_print_debug)
>       notify->array: struct notify_array
>          num_depths               : 0x00000000 (0)
>          depth: ARRAY(0)
>[2011/05/16 10:25:09.659409,  2] smbd/open.c:634(open_file)
>  office opened file test7 read=No write=Yes (numopen=1)
>[2011/05/16 10:25:09.659438, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 110800000000000008C0
>[2011/05/16 10:25:09.659461, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0xb781a748
>[2011/05/16 10:25:09.659482, 10] smbd/open.c:1034(delay_for_oplocks)
>  delay_for_oplocks: oplock type 0x10 on file test7
>[2011/05/16 10:25:09.659500, 10] smbd/open.c:1034(delay_for_oplocks)
>  delay_for_oplocks: oplock type 0x10 on file test7
>[2011/05/16 10:25:09.659519,  5] smbd/oplock.c:86(set_file_oplock)
>  set_file_oplock: granted oplock on file test7, 811:1bac008:0/1, tv_sec = 4dd0df65, tv_usec = a0ea1
>[2011/05/16 10:25:09.659547, 10] locking/locking.c:726(unparse_share_modes)
>  unparse_share_modes: del: 0, owrt: Mon May 16 10:25:09 2011 CEST cwrt: Thu Jan  1 01:00:00 1970 CET, tok: 0, num: 1
>[2011/05/16 10:25:09.659589, 10] locking/locking.c:518(print_share_mode_table)
>  print_share_mode_table: share_mode_entry[0]:  pid = 9822, share_access = 0x7, private_options = 0x40, access_mask = 0x82, mid = 0x0, type= 0x10, gen_id = 1, uid = 1019, flags = 1, file_id 811:1bac008:0
>[2011/05/16 10:25:09.659964, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 110800000000000008C0
>[2011/05/16 10:25:09.660031, 10] smbd/open.c:3198(create_file_unixpath)
>  create_file_unixpath: info=2
>[2011/05/16 10:25:09.660079, 10] smbd/open.c:3454(create_file_default)
>  create_file: info=2
>[2011/05/16 10:25:09.660119, 10] smbd/trans2.c:3873(store_file_unix_basic)
>  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
>[2011/05/16 10:25:09.660158,  4] smbd/trans2.c:3874(store_file_unix_basic)
>  store_file_unix_basic: st_mode=100646
>[2011/05/16 10:25:09.660225,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 112, useable_space = 16410
>[2011/05/16 10:25:09.660267,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 112, paramsize = 2, datasize = 112
>[2011/05/16 10:25:09.660308,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:09.660331,  5] lib/util.c:627(show_msg)
>  size=172
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=2587
>  smb_uid=100
>  smb_mid=445
>  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
>[2011/05/16 10:25:09.660950, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 00 00 00 00 00 3B   49 02 00 00 00 00 02 00   .......; I.......
>  [0010] 00 00 00 00 00 00 00 00   00 00 00 00 00 00 00 00   ........ ........
>  [0020] 00 80 60 8B C4 A2 13 CC   01 80 60 8B C4 A2 13 CC   ..`..... ..`.....
>  [0030] 01 80 60 8B C4 A2 13 CC   01 FB 03 00 00 00 00 00   ..`..... ........
>  [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 08 C0 BA   ........ ........
>  [0060] 01 00 00 00 00 A6 01 00   00 00 00 00 00 01 00 00   ........ ........
>  [0070] 00 00 00 00 00                                    ..... 
>[2011/05/16 10:25:09.661763, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 41
>[2011/05/16 10:25:09.661809,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x29
>[2011/05/16 10:25:09.661848,  3] smbd/process.c:1489(process_smb)
>  Transaction 10 of length 45 (0 toread)
>[2011/05/16 10:25:09.661897,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:09.661920,  5] lib/util.c:627(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=2587
>  smb_uid=100
>  smb_mid=446
>  smt_wct=3
>  smb_vwv[ 0]=18747 (0x493B)
>  smb_vwv[ 1]=65535 (0xFFFF)
>  smb_vwv[ 2]=65535 (0xFFFF)
>  smb_bcc=0
>[2011/05/16 10:25:09.662177,  3] smbd/process.c:1298(switch_message)
>  switch message SMBclose (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:09.662216,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:09.662258,  3] smbd/reply.c:4653(reply_close)
>  close fd=32 fnum=18747 (numopen=1)
>[2011/05/16 10:25:09.662300,  6] smbd/close.c:501(set_close_write_time)
>  close_write_time: Thu Jan  1 00:59:59 1970
>[2011/05/16 10:25:09.662365, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 110800000000000008C0
>[2011/05/16 10:25:09.662407, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0xb78197f8
>[2011/05/16 10:25:09.662446, 10] locking/locking.c:552(parse_share_modes)
>  parse_share_modes: delete_on_close: 0, owrt: Mon May 16 10:25:09 2011 CEST, cwrt: Thu Jan  1 01:00:00 1970 CET, tok: 0, num_share_modes: 1
>[2011/05/16 10:25:09.662509, 10] locking/locking.c:655(parse_share_modes)
>  parse_share_modes: share_mode_entry[0]:  pid = 9822, share_access = 0x7, private_options = 0x40, access_mask = 0x82, mid = 0x0, type= 0x10, gen_id = 1, uid = 1019, flags = 1, file_id 811:1bac008:0
>[2011/05/16 10:25:09.662555, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 110800000000000008C0
>[2011/05/16 10:25:09.662607, 10] locking/posix.c:512(get_windows_lock_ref_count)
>  get_windows_lock_count for file test7 = 0
>[2011/05/16 10:25:09.662650, 10] locking/posix.c:538(delete_windows_lock_ref_count)
>  delete_windows_lock_ref_count for file test7
>[2011/05/16 10:25:09.662708,  2] smbd/close.c:656(close_normal_file)
>  office closed file test7 (numopen=0) NT_STATUS_OK
>[2011/05/16 10:25:09.662827,  5] smbd/files.c:497(file_free)
>  freed files structure 18747 (0 used)
>[2011/05/16 10:25:09.662867,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:09.662891,  5] lib/util.c:627(show_msg)
>  size=35
>  smb_com=0x4
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=2587
>  smb_uid=100
>  smb_mid=446
>  smt_wct=0
>  smb_bcc=0
>[2011/05/16 10:25:10.953568, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 68
>[2011/05/16 10:25:10.953624,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x44
>[2011/05/16 10:25:10.953645,  3] smbd/process.c:1489(process_smb)
>  Transaction 11 of length 72 (0 toread)
>[2011/05/16 10:25:10.953664,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:10.953675,  5] lib/util.c:627(show_msg)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=447
>  smt_wct=15
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=  100 (0x64)
>  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]=    2 (0x2)
>  smb_vwv[10]=   66 (0x42)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    3 (0x3)
>  smb_bcc=3
>[2011/05/16 10:25:10.953856, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 01 02                                          ... 
>[2011/05/16 10:25:10.953887,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:10.953906,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:10.953948,  3] smbd/trans2.c:3463(call_trans2qfsinfo)
>  call_trans2qfsinfo: level = 513
>[2011/05/16 10:25:10.953974,  3] smbd/trans2.c:2906(smbd_do_qfsinfo)
>  smbd_do_qfsinfo: level = 513
>[2011/05/16 10:25:10.954166,  5] smbd/trans2.c:3286(smbd_do_qfsinfo)
>  smbd_do_qfsinfo : SMB_QUERY_POSIX_FS_INFO succsessful
>[2011/05/16 10:25:10.954189,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 0, data_sent_thistime = 56, useable_space = 16412
>[2011/05/16 10:25:10.954208,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 0, data_to_send = 56, paramsize = 0, datasize = 56
>[2011/05/16 10:25:10.954226,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:10.954237,  5] lib/util.c:627(show_msg)
>  size=112
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=447
>  smt_wct=10
>  smb_vwv[ 0]=    0 (0x0)
>  smb_vwv[ 1]=   56 (0x38)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    0 (0x0)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   56 (0x38)
>  smb_vwv[ 7]=   56 (0x38)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=57
>[2011/05/16 10:25:10.954373, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 10 00 00 00 10 00   00 82 92 53 0E 00 00 00   ........ ...S....
>  [0010] 00 F4 2E 3D 08 00 00 00   00 98 E1 82 07 00 00 00   ...=.... ........
>  [0020] 00 00 A0 A3 03 00 00 00   00 62 9E 97 03 00 00 00   ........ .b......
>  [0030] 00 A9 39 2C 52 00 00 00   00                       ..9,R... .
>[2011/05/16 10:25:10.954481,  4] smbd/trans2.c:3479(call_trans2qfsinfo)
>  SMBtrans2 info_level = 513
>[2011/05/16 10:25:10.954676, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 74
>[2011/05/16 10:25:10.954848,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x4a
>[2011/05/16 10:25:10.954888,  3] smbd/process.c:1489(process_smb)
>  Transaction 12 of length 78 (0 toread)
>[2011/05/16 10:25:10.954927,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:10.954949,  5] lib/util.c:627(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=1064
>  smb_uid=100
>  smb_mid=448
>  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
>[2011/05/16 10:25:10.955420, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 02 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:10.955471,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:10.955511,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:10.955550,  3] smbd/trans2.c:5099(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
>[2011/05/16 10:25:10.955596,  5] smbd/filename.c:169(unix_convert)
>  unix_convert called on file ""
>[2011/05/16 10:25:10.955636,  5] smbd/filename.c:203(unix_convert)
>  conversion finished "" -> .
>[2011/05/16 10:25:10.955675,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [.] [/home/alle]
>[2011/05/16 10:25:10.955796, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/alle]
>[2011/05/16 10:25:10.955838,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: . reduced to /home/alle
>[2011/05/16 10:25:10.955894, 10] locking/locking.c:926(fetch_share_mode_unlocked)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2011/05/16 10:25:10.955933,  3] smbd/trans2.c:5225(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0
>[2011/05/16 10:25:10.955985,  5] smbd/trans2.c:4197(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: . (fnum = -1) level=512 max_data=4000
>[2011/05/16 10:25:10.956047,  8] smbd/dosmode.c:613(dos_mode)
>  dos_mode: .
>[2011/05/16 10:25:10.956088,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning d
>[2011/05/16 10:25:10.956128,  8] smbd/dosmode.c:666(dos_mode)
>  dos_mode returning d
>[2011/05/16 10:25:10.956172, 10] smbd/trans2.c:3873(store_file_unix_basic)
>  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
>[2011/05/16 10:25:10.956210,  4] smbd/trans2.c:3874(store_file_unix_basic)
>  store_file_unix_basic: st_mode=40777
>[2011/05/16 10:25:10.956249,  4] smbd/trans2.c:4724(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: 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=ffffff80, 17=60, 18=ffffff8b, 19=ffffffc4, 20=ffffffa2, 21=13, 22=ffffffcc, 23=1, 24=0, 25=ffffffd5, 26=ffffffa9, 27=1a, 28=ffffff9e, 29=13, 30=ffffffcc, 31=1, 32=ffffff80, 33=60, 34=ffffff8b, 35=ffffffc4, 36=ffffffa2, 37=13, 38=ffffffcc, 39=1, 40=fffffffb, 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=5e, 77=ffffffa2, 78=ffffffae, 79=2, 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=f, 93=0, 94=0, 95=0, 96=0, 97=0, 98=0, 99=0, 
>[2011/05/16 10:25:10.956369,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 100, useable_space = 16410
>[2011/05/16 10:25:10.956409,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 100, paramsize = 2, datasize = 100
>[2011/05/16 10:25:10.956449,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:10.956472,  5] lib/util.c:627(show_msg)
>  size=160
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=448
>  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
>[2011/05/16 10:25:10.956926, 10] ../lib/util/util.c:278(_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 80 60 8B   C4 A2 13 CC 01 00 D5 A9   ......`. ........
>  [0020] 1A 9E 13 CC 01 80 60 8B   C4 A2 13 CC 01 FB 03 00   ......`. ........
>  [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 5E A2 AE 02 00 00 00   00 FF 01 00 00 00 00 00   .^...... ........
>  [0060] 00 0F 00 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:20.952227, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 68
>[2011/05/16 10:25:20.952288,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x44
>[2011/05/16 10:25:20.952308,  3] smbd/process.c:1489(process_smb)
>  Transaction 13 of length 72 (0 toread)
>[2011/05/16 10:25:20.952326,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:20.952338,  5] lib/util.c:627(show_msg)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=449
>  smt_wct=15
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=  100 (0x64)
>  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]=    2 (0x2)
>  smb_vwv[10]=   66 (0x42)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    3 (0x3)
>  smb_bcc=3
>[2011/05/16 10:25:20.952497, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 01 02                                          ... 
>[2011/05/16 10:25:20.952527,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:20.952564,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:20.952585,  3] smbd/trans2.c:3463(call_trans2qfsinfo)
>  call_trans2qfsinfo: level = 513
>[2011/05/16 10:25:20.952609,  3] smbd/trans2.c:2906(smbd_do_qfsinfo)
>  smbd_do_qfsinfo: level = 513
>[2011/05/16 10:25:20.952805,  5] smbd/trans2.c:3286(smbd_do_qfsinfo)
>  smbd_do_qfsinfo : SMB_QUERY_POSIX_FS_INFO succsessful
>[2011/05/16 10:25:20.952828,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 0, data_sent_thistime = 56, useable_space = 16412
>[2011/05/16 10:25:20.952846,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 0, data_to_send = 56, paramsize = 0, datasize = 56
>[2011/05/16 10:25:20.952863,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:20.952874,  5] lib/util.c:627(show_msg)
>  size=112
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=449
>  smt_wct=10
>  smb_vwv[ 0]=    0 (0x0)
>  smb_vwv[ 1]=   56 (0x38)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    0 (0x0)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   56 (0x38)
>  smb_vwv[ 7]=   56 (0x38)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=57
>[2011/05/16 10:25:20.953005, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 10 00 00 00 10 00   00 82 92 53 0E 00 00 00   ........ ...S....
>  [0010] 00 F3 2E 3D 08 00 00 00   00 97 E1 82 07 00 00 00   ...=.... ........
>  [0020] 00 00 A0 A3 03 00 00 00   00 62 9E 97 03 00 00 00   ........ .b......
>  [0030] 00 A9 39 2C 52 00 00 00   00                       ..9,R... .
>[2011/05/16 10:25:20.953114,  4] smbd/trans2.c:3479(call_trans2qfsinfo)
>  SMBtrans2 info_level = 513
>[2011/05/16 10:25:20.953337, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 74
>[2011/05/16 10:25:20.953361,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x4a
>[2011/05/16 10:25:20.953379,  3] smbd/process.c:1489(process_smb)
>  Transaction 14 of length 78 (0 toread)
>[2011/05/16 10:25:20.953397,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:20.953408,  5] lib/util.c:627(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=1064
>  smb_uid=100
>  smb_mid=450
>  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
>[2011/05/16 10:25:20.953567, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 02 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:20.953596,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:20.953614,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:20.953632,  3] smbd/trans2.c:5099(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
>[2011/05/16 10:25:20.953655,  5] smbd/filename.c:169(unix_convert)
>  unix_convert called on file ""
>[2011/05/16 10:25:20.953674,  5] smbd/filename.c:203(unix_convert)
>  conversion finished "" -> .
>[2011/05/16 10:25:20.953691,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [.] [/home/alle]
>[2011/05/16 10:25:20.953930, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/alle]
>[2011/05/16 10:25:20.953971,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: . reduced to /home/alle
>[2011/05/16 10:25:20.954037, 10] locking/locking.c:926(fetch_share_mode_unlocked)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2011/05/16 10:25:20.954077,  3] smbd/trans2.c:5225(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0
>[2011/05/16 10:25:20.954138,  5] smbd/trans2.c:4197(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: . (fnum = -1) level=512 max_data=4000
>[2011/05/16 10:25:20.954188,  8] smbd/dosmode.c:613(dos_mode)
>  dos_mode: .
>[2011/05/16 10:25:20.954229,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning d
>[2011/05/16 10:25:20.954268,  8] smbd/dosmode.c:666(dos_mode)
>  dos_mode returning d
>[2011/05/16 10:25:20.954311, 10] smbd/trans2.c:3873(store_file_unix_basic)
>  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
>[2011/05/16 10:25:20.954349,  4] smbd/trans2.c:3874(store_file_unix_basic)
>  store_file_unix_basic: st_mode=40777
>[2011/05/16 10:25:20.954388,  4] smbd/trans2.c:4724(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: 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=ffffff80, 17=60, 18=ffffff8b, 19=ffffffc4, 20=ffffffa2, 21=13, 22=ffffffcc, 23=1, 24=0, 25=ffffffd5, 26=ffffffa9, 27=1a, 28=ffffff9e, 29=13, 30=ffffffcc, 31=1, 32=ffffff80, 33=60, 34=ffffff8b, 35=ffffffc4, 36=ffffffa2, 37=13, 38=ffffffcc, 39=1, 40=fffffffb, 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=5e, 77=ffffffa2, 78=ffffffae, 79=2, 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=f, 93=0, 94=0, 95=0, 96=0, 97=0, 98=0, 99=0, 
>[2011/05/16 10:25:20.954508,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 100, useable_space = 16410
>[2011/05/16 10:25:20.954548,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 100, paramsize = 2, datasize = 100
>[2011/05/16 10:25:20.954588,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:20.954610,  5] lib/util.c:627(show_msg)
>  size=160
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=450
>  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
>[2011/05/16 10:25:20.955133, 10] ../lib/util/util.c:278(_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 80 60 8B   C4 A2 13 CC 01 00 D5 A9   ......`. ........
>  [0020] 1A 9E 13 CC 01 80 60 8B   C4 A2 13 CC 01 FB 03 00   ......`. ........
>  [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 5E A2 AE 02 00 00 00   00 FF 01 00 00 00 00 00   .^...... ........
>  [0060] 00 0F 00 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:30.953637, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 68
>[2011/05/16 10:25:30.953711,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x44
>[2011/05/16 10:25:30.953732,  3] smbd/process.c:1489(process_smb)
>  Transaction 15 of length 72 (0 toread)
>[2011/05/16 10:25:30.953751,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:30.953763,  5] lib/util.c:627(show_msg)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=451
>  smt_wct=15
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=  100 (0x64)
>  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]=    2 (0x2)
>  smb_vwv[10]=   66 (0x42)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    3 (0x3)
>  smb_bcc=3
>[2011/05/16 10:25:30.953955, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 01 02                                          ... 
>[2011/05/16 10:25:30.953985,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:30.954005,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:30.954027,  3] smbd/trans2.c:3463(call_trans2qfsinfo)
>  call_trans2qfsinfo: level = 513
>[2011/05/16 10:25:30.954051,  3] smbd/trans2.c:2906(smbd_do_qfsinfo)
>  smbd_do_qfsinfo: level = 513
>[2011/05/16 10:25:30.954233,  5] smbd/trans2.c:3286(smbd_do_qfsinfo)
>  smbd_do_qfsinfo : SMB_QUERY_POSIX_FS_INFO succsessful
>[2011/05/16 10:25:30.954255,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 0, data_sent_thistime = 56, useable_space = 16412
>[2011/05/16 10:25:30.954274,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 0, data_to_send = 56, paramsize = 0, datasize = 56
>[2011/05/16 10:25:30.954292,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:30.954303,  5] lib/util.c:627(show_msg)
>  size=112
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=451
>  smt_wct=10
>  smb_vwv[ 0]=    0 (0x0)
>  smb_vwv[ 1]=   56 (0x38)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    0 (0x0)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   56 (0x38)
>  smb_vwv[ 7]=   56 (0x38)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=57
>[2011/05/16 10:25:30.954441, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 10 00 00 00 10 00   00 82 92 53 0E 00 00 00   ........ ...S....
>  [0010] 00 F3 2E 3D 08 00 00 00   00 97 E1 82 07 00 00 00   ...=.... ........
>  [0020] 00 00 A0 A3 03 00 00 00   00 62 9E 97 03 00 00 00   ........ .b......
>  [0030] 00 A9 39 2C 52 00 00 00   00                       ..9,R... .
>[2011/05/16 10:25:30.954549,  4] smbd/trans2.c:3479(call_trans2qfsinfo)
>  SMBtrans2 info_level = 513
>[2011/05/16 10:25:30.954881, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 74
>[2011/05/16 10:25:30.954907,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x4a
>[2011/05/16 10:25:30.954926,  3] smbd/process.c:1489(process_smb)
>  Transaction 16 of length 78 (0 toread)
>[2011/05/16 10:25:30.954943,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:30.954955,  5] lib/util.c:627(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=1064
>  smb_uid=100
>  smb_mid=452
>  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
>[2011/05/16 10:25:30.955131, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 02 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:30.955170,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:30.955197,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:30.955216,  3] smbd/trans2.c:5099(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
>[2011/05/16 10:25:30.955240,  5] smbd/filename.c:169(unix_convert)
>  unix_convert called on file ""
>[2011/05/16 10:25:30.955259,  5] smbd/filename.c:203(unix_convert)
>  conversion finished "" -> .
>[2011/05/16 10:25:30.955277,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [.] [/home/alle]
>[2011/05/16 10:25:30.955299, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/alle]
>[2011/05/16 10:25:30.955317,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: . reduced to /home/alle
>[2011/05/16 10:25:30.955360, 10] locking/locking.c:926(fetch_share_mode_unlocked)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2011/05/16 10:25:30.955379,  3] smbd/trans2.c:5225(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0
>[2011/05/16 10:25:30.955398,  5] smbd/trans2.c:4197(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: . (fnum = -1) level=512 max_data=4000
>[2011/05/16 10:25:30.955417,  8] smbd/dosmode.c:613(dos_mode)
>  dos_mode: .
>[2011/05/16 10:25:30.955437,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning d
>[2011/05/16 10:25:30.955455,  8] smbd/dosmode.c:666(dos_mode)
>  dos_mode returning d
>[2011/05/16 10:25:30.955477, 10] smbd/trans2.c:3873(store_file_unix_basic)
>  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
>[2011/05/16 10:25:30.955495,  4] smbd/trans2.c:3874(store_file_unix_basic)
>  store_file_unix_basic: st_mode=40777
>[2011/05/16 10:25:30.955513,  4] smbd/trans2.c:4724(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: 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=ffffff80, 17=60, 18=ffffff8b, 19=ffffffc4, 20=ffffffa2, 21=13, 22=ffffffcc, 23=1, 24=0, 25=ffffffd5, 26=ffffffa9, 27=1a, 28=ffffff9e, 29=13, 30=ffffffcc, 31=1, 32=ffffff80, 33=60, 34=ffffff8b, 35=ffffffc4, 36=ffffffa2, 37=13, 38=ffffffcc, 39=1, 40=fffffffb, 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=5e, 77=ffffffa2, 78=ffffffae, 79=2, 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=f, 93=0, 94=0, 95=0, 96=0, 97=0, 98=0, 99=0, 
>[2011/05/16 10:25:30.955605,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 100, useable_space = 16410
>[2011/05/16 10:25:30.955624,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 100, paramsize = 2, datasize = 100
>[2011/05/16 10:25:30.955642,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:30.955653,  5] lib/util.c:627(show_msg)
>  size=160
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=452
>  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
>[2011/05/16 10:25:30.955802, 10] ../lib/util/util.c:278(_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 80 60 8B   C4 A2 13 CC 01 00 D5 A9   ......`. ........
>  [0020] 1A 9E 13 CC 01 80 60 8B   C4 A2 13 CC 01 FB 03 00   ......`. ........
>  [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 5E A2 AE 02 00 00 00   00 FF 01 00 00 00 00 00   .^...... ........
>  [0060] 00 0F 00 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:40.950873, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 68
>[2011/05/16 10:25:40.950932,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x44
>[2011/05/16 10:25:40.950952,  3] smbd/process.c:1489(process_smb)
>  Transaction 17 of length 72 (0 toread)
>[2011/05/16 10:25:40.950971,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:40.950982,  5] lib/util.c:627(show_msg)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=453
>  smt_wct=15
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=  100 (0x64)
>  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]=    2 (0x2)
>  smb_vwv[10]=   66 (0x42)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    3 (0x3)
>  smb_bcc=3
>[2011/05/16 10:25:40.951165, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 01 02                                          ... 
>[2011/05/16 10:25:40.951195,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:40.951215,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:40.951236,  3] smbd/trans2.c:3463(call_trans2qfsinfo)
>  call_trans2qfsinfo: level = 513
>[2011/05/16 10:25:40.951260,  3] smbd/trans2.c:2906(smbd_do_qfsinfo)
>  smbd_do_qfsinfo: level = 513
>[2011/05/16 10:25:40.951444,  5] smbd/trans2.c:3286(smbd_do_qfsinfo)
>  smbd_do_qfsinfo : SMB_QUERY_POSIX_FS_INFO succsessful
>[2011/05/16 10:25:40.951466,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 0, data_sent_thistime = 56, useable_space = 16412
>[2011/05/16 10:25:40.951484,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 0, data_to_send = 56, paramsize = 0, datasize = 56
>[2011/05/16 10:25:40.951502,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:40.951513,  5] lib/util.c:627(show_msg)
>  size=112
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=453
>  smt_wct=10
>  smb_vwv[ 0]=    0 (0x0)
>  smb_vwv[ 1]=   56 (0x38)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    0 (0x0)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   56 (0x38)
>  smb_vwv[ 7]=   56 (0x38)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=57
>[2011/05/16 10:25:40.951650, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 10 00 00 00 10 00   00 82 92 53 0E 00 00 00   ........ ...S....
>  [0010] 00 F3 2E 3D 08 00 00 00   00 97 E1 82 07 00 00 00   ...=.... ........
>  [0020] 00 00 A0 A3 03 00 00 00   00 62 9E 97 03 00 00 00   ........ .b......
>  [0030] 00 A9 39 2C 52 00 00 00   00                       ..9,R... .
>[2011/05/16 10:25:40.951771,  4] smbd/trans2.c:3479(call_trans2qfsinfo)
>  SMBtrans2 info_level = 513
>[2011/05/16 10:25:40.951966, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 74
>[2011/05/16 10:25:40.951991,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x4a
>[2011/05/16 10:25:40.952009,  3] smbd/process.c:1489(process_smb)
>  Transaction 18 of length 78 (0 toread)
>[2011/05/16 10:25:40.952027,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:40.952038,  5] lib/util.c:627(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=1064
>  smb_uid=100
>  smb_mid=454
>  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
>[2011/05/16 10:25:40.952204, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 02 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:40.952233,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:40.952252,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:40.952270,  3] smbd/trans2.c:5099(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
>[2011/05/16 10:25:40.952294,  5] smbd/filename.c:169(unix_convert)
>  unix_convert called on file ""
>[2011/05/16 10:25:40.952313,  5] smbd/filename.c:203(unix_convert)
>  conversion finished "" -> .
>[2011/05/16 10:25:40.952331,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [.] [/home/alle]
>[2011/05/16 10:25:40.952354, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/alle]
>[2011/05/16 10:25:40.952383,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: . reduced to /home/alle
>[2011/05/16 10:25:40.952414, 10] locking/locking.c:926(fetch_share_mode_unlocked)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2011/05/16 10:25:40.952432,  3] smbd/trans2.c:5225(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0
>[2011/05/16 10:25:40.952451,  5] smbd/trans2.c:4197(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: . (fnum = -1) level=512 max_data=4000
>[2011/05/16 10:25:40.952469,  8] smbd/dosmode.c:613(dos_mode)
>  dos_mode: .
>[2011/05/16 10:25:40.952489,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning d
>[2011/05/16 10:25:40.952507,  8] smbd/dosmode.c:666(dos_mode)
>  dos_mode returning d
>[2011/05/16 10:25:40.952529, 10] smbd/trans2.c:3873(store_file_unix_basic)
>  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
>[2011/05/16 10:25:40.952545,  4] smbd/trans2.c:3874(store_file_unix_basic)
>  store_file_unix_basic: st_mode=40777
>[2011/05/16 10:25:40.952564,  4] smbd/trans2.c:4724(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: 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=ffffff80, 17=60, 18=ffffff8b, 19=ffffffc4, 20=ffffffa2, 21=13, 22=ffffffcc, 23=1, 24=0, 25=ffffffd5, 26=ffffffa9, 27=1a, 28=ffffff9e, 29=13, 30=ffffffcc, 31=1, 32=ffffff80, 33=60, 34=ffffff8b, 35=ffffffc4, 36=ffffffa2, 37=13, 38=ffffffcc, 39=1, 40=fffffffb, 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=5e, 77=ffffffa2, 78=ffffffae, 79=2, 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=f, 93=0, 94=0, 95=0, 96=0, 97=0, 98=0, 99=0, 
>[2011/05/16 10:25:40.952657,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 100, useable_space = 16410
>[2011/05/16 10:25:40.952675,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 100, paramsize = 2, datasize = 100
>[2011/05/16 10:25:40.952692,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:40.952926,  5] lib/util.c:627(show_msg)
>  size=160
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=454
>  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
>[2011/05/16 10:25:40.953319, 10] ../lib/util/util.c:278(_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 80 60 8B   C4 A2 13 CC 01 00 D5 A9   ......`. ........
>  [0020] 1A 9E 13 CC 01 80 60 8B   C4 A2 13 CC 01 FB 03 00   ......`. ........
>  [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 5E A2 AE 02 00 00 00   00 FF 01 00 00 00 00 00   .^...... ........
>  [0060] 00 0F 00 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:43.037919, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 68
>[2011/05/16 10:25:43.037964,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x44
>[2011/05/16 10:25:43.037984,  3] smbd/process.c:1489(process_smb)
>  Transaction 19 of length 72 (0 toread)
>[2011/05/16 10:25:43.038002,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:43.038014,  5] lib/util.c:627(show_msg)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=1510
>  smb_uid=100
>  smb_mid=455
>  smt_wct=15
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=  100 (0x64)
>  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]=    2 (0x2)
>  smb_vwv[10]=   66 (0x42)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    3 (0x3)
>  smb_bcc=3
>[2011/05/16 10:25:43.038195, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 01 02                                          ... 
>[2011/05/16 10:25:43.038226,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:43.038245,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:43.038266,  3] smbd/trans2.c:3463(call_trans2qfsinfo)
>  call_trans2qfsinfo: level = 513
>[2011/05/16 10:25:43.038291,  3] smbd/trans2.c:2906(smbd_do_qfsinfo)
>  smbd_do_qfsinfo: level = 513
>[2011/05/16 10:25:43.038472,  5] smbd/trans2.c:3286(smbd_do_qfsinfo)
>  smbd_do_qfsinfo : SMB_QUERY_POSIX_FS_INFO succsessful
>[2011/05/16 10:25:43.038495,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 0, data_sent_thistime = 56, useable_space = 16412
>[2011/05/16 10:25:43.038513,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 0, data_to_send = 56, paramsize = 0, datasize = 56
>[2011/05/16 10:25:43.038530,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:43.038542,  5] lib/util.c:627(show_msg)
>  size=112
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1510
>  smb_uid=100
>  smb_mid=455
>  smt_wct=10
>  smb_vwv[ 0]=    0 (0x0)
>  smb_vwv[ 1]=   56 (0x38)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    0 (0x0)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   56 (0x38)
>  smb_vwv[ 7]=   56 (0x38)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=57
>[2011/05/16 10:25:43.038673, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 10 00 00 00 10 00   00 82 92 53 0E 00 00 00   ........ ...S....
>  [0010] 00 F3 2E 3D 08 00 00 00   00 97 E1 82 07 00 00 00   ...=.... ........
>  [0020] 00 00 A0 A3 03 00 00 00   00 62 9E 97 03 00 00 00   ........ .b......
>  [0030] 00 A9 39 2C 52 00 00 00   00                       ..9,R... .
>[2011/05/16 10:25:43.038793,  4] smbd/trans2.c:3479(call_trans2qfsinfo)
>  SMBtrans2 info_level = 513
>[2011/05/16 10:25:43.038987, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 74
>[2011/05/16 10:25:43.039011,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x4a
>[2011/05/16 10:25:43.039029,  3] smbd/process.c:1489(process_smb)
>  Transaction 20 of length 78 (0 toread)
>[2011/05/16 10:25:43.039047,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:43.039058,  5] lib/util.c:627(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=1510
>  smb_uid=100
>  smb_mid=456
>  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
>[2011/05/16 10:25:43.039221, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 02 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:43.039250,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:43.039268,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:43.039287,  3] smbd/trans2.c:5099(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
>[2011/05/16 10:25:43.039310,  5] smbd/filename.c:169(unix_convert)
>  unix_convert called on file ""
>[2011/05/16 10:25:43.039329,  5] smbd/filename.c:203(unix_convert)
>  conversion finished "" -> .
>[2011/05/16 10:25:43.039358,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [.] [/home/alle]
>[2011/05/16 10:25:43.039380, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/alle]
>[2011/05/16 10:25:43.039399,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: . reduced to /home/alle
>[2011/05/16 10:25:43.039429, 10] locking/locking.c:926(fetch_share_mode_unlocked)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2011/05/16 10:25:43.039447,  3] smbd/trans2.c:5225(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0
>[2011/05/16 10:25:43.039467,  5] smbd/trans2.c:4197(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: . (fnum = -1) level=512 max_data=4000
>[2011/05/16 10:25:43.039485,  8] smbd/dosmode.c:613(dos_mode)
>  dos_mode: .
>[2011/05/16 10:25:43.039505,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning d
>[2011/05/16 10:25:43.039523,  8] smbd/dosmode.c:666(dos_mode)
>  dos_mode returning d
>[2011/05/16 10:25:43.039544, 10] smbd/trans2.c:3873(store_file_unix_basic)
>  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
>[2011/05/16 10:25:43.039561,  4] smbd/trans2.c:3874(store_file_unix_basic)
>  store_file_unix_basic: st_mode=40777
>[2011/05/16 10:25:43.039580,  4] smbd/trans2.c:4724(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: 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=ffffff80, 17=60, 18=ffffff8b, 19=ffffffc4, 20=ffffffa2, 21=13, 22=ffffffcc, 23=1, 24=0, 25=ffffffd5, 26=ffffffa9, 27=1a, 28=ffffff9e, 29=13, 30=ffffffcc, 31=1, 32=ffffff80, 33=60, 34=ffffff8b, 35=ffffffc4, 36=ffffffa2, 37=13, 38=ffffffcc, 39=1, 40=fffffffb, 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=5e, 77=ffffffa2, 78=ffffffae, 79=2, 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=f, 93=0, 94=0, 95=0, 96=0, 97=0, 98=0, 99=0, 
>[2011/05/16 10:25:43.039671,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 100, useable_space = 16410
>[2011/05/16 10:25:43.039689,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 100, paramsize = 2, datasize = 100
>[2011/05/16 10:25:43.039951,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:43.039975,  5] lib/util.c:627(show_msg)
>  size=160
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1510
>  smb_uid=100
>  smb_mid=456
>  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
>[2011/05/16 10:25:43.040347, 10] ../lib/util/util.c:278(_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 80 60 8B   C4 A2 13 CC 01 00 D5 A9   ......`. ........
>  [0020] 1A 9E 13 CC 01 80 60 8B   C4 A2 13 CC 01 FB 03 00   ......`. ........
>  [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 5E A2 AE 02 00 00 00   00 FF 01 00 00 00 00 00   .^...... ........
>  [0060] 00 0F 00 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:47.761623, 10] lib/events.c:131(run_events)
>  Running timed event "smbd_idle_event_handler" 0xb7825698
>[2011/05/16 10:25:47.761658, 10] smbd/process.c:683(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) called
>[2011/05/16 10:25:47.761678, 10] smbd/process.c:694(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) rescheduled
>[2011/05/16 10:25:47.761716, 10] lib/events.c:131(run_events)
>  Running timed event "smbd_idle_event_handler" 0xb780c978
>[2011/05/16 10:25:47.761758, 10] smbd/process.c:683(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) called
>[2011/05/16 10:25:47.761776,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:25:47.761793,  5] auth/token_util.c:525(debug_nt_user_token)
>  NT user token: (NULL)
>[2011/05/16 10:25:47.761810,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2011/05/16 10:25:47.761839,  5] smbd/uid.c:369(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2011/05/16 10:25:47.761870, 10] smbd/process.c:694(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) rescheduled
>[2011/05/16 10:25:50.949072, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 68
>[2011/05/16 10:25:50.949131,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x44
>[2011/05/16 10:25:50.949151,  3] smbd/process.c:1489(process_smb)
>  Transaction 21 of length 72 (0 toread)
>[2011/05/16 10:25:50.949169,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:50.949180,  5] lib/util.c:627(show_msg)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=457
>  smt_wct=15
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=  100 (0x64)
>  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]=    2 (0x2)
>  smb_vwv[10]=   66 (0x42)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    3 (0x3)
>  smb_bcc=3
>[2011/05/16 10:25:50.949341, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 01 02                                          ... 
>[2011/05/16 10:25:50.949370,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:50.949393,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (1019, 100) - sec_ctx_stack_ndx = 0
>[2011/05/16 10:25:50.949411,  5] auth/token_util.c:531(debug_nt_user_token)
>  NT user token of user S-1-5-21-3937548381-614798192-88495934-501
>  contains 6 SIDs
>  SID[  0]: S-1-5-21-3937548381-614798192-88495934-501
>  SID[  1]: S-1-22-2-100
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-32-546
>  SID[  5]: S-1-22-1-1019
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2011/05/16 10:25:50.949490,  5] auth/token_util.c:551(debug_unix_user_token)
>  UNIX token of user 1019
>  Primary group is 100 and contains 1 supplementary groups
>  Group[  0]: 100
>[2011/05/16 10:25:50.949531,  5] smbd/uid.c:354(change_to_user)
>  change_to_user uid=(0,1019) gid=(0,100)
>[2011/05/16 10:25:50.949555,  3] smbd/trans2.c:3463(call_trans2qfsinfo)
>  call_trans2qfsinfo: level = 513
>[2011/05/16 10:25:50.949576,  3] smbd/trans2.c:2906(smbd_do_qfsinfo)
>  smbd_do_qfsinfo: level = 513
>[2011/05/16 10:25:50.949772,  5] smbd/trans2.c:3286(smbd_do_qfsinfo)
>  smbd_do_qfsinfo : SMB_QUERY_POSIX_FS_INFO succsessful
>[2011/05/16 10:25:50.949795,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 0, data_sent_thistime = 56, useable_space = 16412
>[2011/05/16 10:25:50.949814,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 0, data_to_send = 56, paramsize = 0, datasize = 56
>[2011/05/16 10:25:50.949831,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:50.949842,  5] lib/util.c:627(show_msg)
>  size=112
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=457
>  smt_wct=10
>  smb_vwv[ 0]=    0 (0x0)
>  smb_vwv[ 1]=   56 (0x38)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    0 (0x0)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   56 (0x38)
>  smb_vwv[ 7]=   56 (0x38)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=57
>[2011/05/16 10:25:50.949974, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 10 00 00 00 10 00   00 82 92 53 0E 00 00 00   ........ ...S....
>  [0010] 00 F3 2E 3D 08 00 00 00   00 97 E1 82 07 00 00 00   ...=.... ........
>  [0020] 00 00 A0 A3 03 00 00 00   00 62 9E 97 03 00 00 00   ........ .b......
>  [0030] 00 A9 39 2C 52 00 00 00   00                       ..9,R... .
>[2011/05/16 10:25:50.950102,  4] smbd/trans2.c:3479(call_trans2qfsinfo)
>  SMBtrans2 info_level = 513
>[2011/05/16 10:25:50.950313, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 74
>[2011/05/16 10:25:50.950338,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x4a
>[2011/05/16 10:25:50.950356,  3] smbd/process.c:1489(process_smb)
>  Transaction 22 of length 78 (0 toread)
>[2011/05/16 10:25:50.950373,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:50.950384,  5] lib/util.c:627(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=1064
>  smb_uid=100
>  smb_mid=458
>  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
>[2011/05/16 10:25:50.950548, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 02 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:25:50.950577,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:25:50.950595,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:25:50.950614,  3] smbd/trans2.c:5099(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
>[2011/05/16 10:25:50.950637,  5] smbd/filename.c:169(unix_convert)
>  unix_convert called on file ""
>[2011/05/16 10:25:50.950656,  5] smbd/filename.c:203(unix_convert)
>  conversion finished "" -> .
>[2011/05/16 10:25:50.950673,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [.] [/home/alle]
>[2011/05/16 10:25:50.950711, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/alle]
>[2011/05/16 10:25:50.950948,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: . reduced to /home/alle
>[2011/05/16 10:25:50.951001, 10] locking/locking.c:926(fetch_share_mode_unlocked)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2011/05/16 10:25:50.951052,  3] smbd/trans2.c:5225(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0
>[2011/05/16 10:25:50.951094,  5] smbd/trans2.c:4197(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: . (fnum = -1) level=512 max_data=4000
>[2011/05/16 10:25:50.951134,  8] smbd/dosmode.c:613(dos_mode)
>  dos_mode: .
>[2011/05/16 10:25:50.951184,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning d
>[2011/05/16 10:25:50.951232,  8] smbd/dosmode.c:666(dos_mode)
>  dos_mode returning d
>[2011/05/16 10:25:50.951277, 10] smbd/trans2.c:3873(store_file_unix_basic)
>  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
>[2011/05/16 10:25:50.951315,  4] smbd/trans2.c:3874(store_file_unix_basic)
>  store_file_unix_basic: st_mode=40777
>[2011/05/16 10:25:50.951354,  4] smbd/trans2.c:4724(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: 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=ffffff80, 17=60, 18=ffffff8b, 19=ffffffc4, 20=ffffffa2, 21=13, 22=ffffffcc, 23=1, 24=0, 25=ffffffd5, 26=ffffffa9, 27=1a, 28=ffffff9e, 29=13, 30=ffffffcc, 31=1, 32=ffffff80, 33=60, 34=ffffff8b, 35=ffffffc4, 36=ffffffa2, 37=13, 38=ffffffcc, 39=1, 40=fffffffb, 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=5e, 77=ffffffa2, 78=ffffffae, 79=2, 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=f, 93=0, 94=0, 95=0, 96=0, 97=0, 98=0, 99=0, 
>[2011/05/16 10:25:50.951490,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 100, useable_space = 16410
>[2011/05/16 10:25:50.951531,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 100, paramsize = 2, datasize = 100
>[2011/05/16 10:25:50.951570,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:25:50.951593,  5] lib/util.c:627(show_msg)
>  size=160
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=458
>  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
>[2011/05/16 10:25:50.952112, 10] ../lib/util/util.c:278(_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 80 60 8B   C4 A2 13 CC 01 00 D5 A9   ......`. ........
>  [0020] 1A 9E 13 CC 01 80 60 8B   C4 A2 13 CC 01 FB 03 00   ......`. ........
>  [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 5E A2 AE 02 00 00 00   00 FF 01 00 00 00 00 00   .^...... ........
>  [0060] 00 0F 00 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:26:00.949105, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 68
>[2011/05/16 10:26:00.949145,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x44
>[2011/05/16 10:26:00.949163,  3] smbd/process.c:1489(process_smb)
>  Transaction 23 of length 72 (0 toread)
>[2011/05/16 10:26:00.949181,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:26:00.949192,  5] lib/util.c:627(show_msg)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=459
>  smt_wct=15
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=  100 (0x64)
>  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]=    2 (0x2)
>  smb_vwv[10]=   66 (0x42)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    3 (0x3)
>  smb_bcc=3
>[2011/05/16 10:26:00.949337, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 01 02                                          ... 
>[2011/05/16 10:26:00.949365,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:26:00.949383,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:26:00.949403,  3] smbd/trans2.c:3463(call_trans2qfsinfo)
>  call_trans2qfsinfo: level = 513
>[2011/05/16 10:26:00.949426,  3] smbd/trans2.c:2906(smbd_do_qfsinfo)
>  smbd_do_qfsinfo: level = 513
>[2011/05/16 10:26:00.949609,  5] smbd/trans2.c:3286(smbd_do_qfsinfo)
>  smbd_do_qfsinfo : SMB_QUERY_POSIX_FS_INFO succsessful
>[2011/05/16 10:26:00.949630,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 0, data_sent_thistime = 56, useable_space = 16412
>[2011/05/16 10:26:00.949647,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 0, data_to_send = 56, paramsize = 0, datasize = 56
>[2011/05/16 10:26:00.949664,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:26:00.949674,  5] lib/util.c:627(show_msg)
>  size=112
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=459
>  smt_wct=10
>  smb_vwv[ 0]=    0 (0x0)
>  smb_vwv[ 1]=   56 (0x38)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    0 (0x0)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   56 (0x38)
>  smb_vwv[ 7]=   56 (0x38)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=57
>[2011/05/16 10:26:00.949827, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 10 00 00 00 10 00   00 82 92 53 0E 00 00 00   ........ ...S....
>  [0010] 00 F3 2E 3D 08 00 00 00   00 97 E1 82 07 00 00 00   ...=.... ........
>  [0020] 00 00 A0 A3 03 00 00 00   00 62 9E 97 03 00 00 00   ........ .b......
>  [0030] 00 A9 39 2C 52 00 00 00   00                       ..9,R... .
>[2011/05/16 10:26:00.949931,  4] smbd/trans2.c:3479(call_trans2qfsinfo)
>  SMBtrans2 info_level = 513
>[2011/05/16 10:26:00.950113, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 74
>[2011/05/16 10:26:00.950133,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x4a
>[2011/05/16 10:26:00.950150,  3] smbd/process.c:1489(process_smb)
>  Transaction 24 of length 78 (0 toread)
>[2011/05/16 10:26:00.950166,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:26:00.950177,  5] lib/util.c:627(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=1064
>  smb_uid=100
>  smb_mid=460
>  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
>[2011/05/16 10:26:00.950322, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 02 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:26:00.950350,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:26:00.950367,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:26:00.950384,  3] smbd/trans2.c:5099(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
>[2011/05/16 10:26:00.950406,  5] smbd/filename.c:169(unix_convert)
>  unix_convert called on file ""
>[2011/05/16 10:26:00.950424,  5] smbd/filename.c:203(unix_convert)
>  conversion finished "" -> .
>[2011/05/16 10:26:00.950441,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [.] [/home/alle]
>[2011/05/16 10:26:00.950461, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/alle]
>[2011/05/16 10:26:00.950479,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: . reduced to /home/alle
>[2011/05/16 10:26:00.950508, 10] locking/locking.c:926(fetch_share_mode_unlocked)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2011/05/16 10:26:00.950524,  3] smbd/trans2.c:5225(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0
>[2011/05/16 10:26:00.950542,  5] smbd/trans2.c:4197(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: . (fnum = -1) level=512 max_data=4000
>[2011/05/16 10:26:00.950559,  8] smbd/dosmode.c:613(dos_mode)
>  dos_mode: .
>[2011/05/16 10:26:00.950578,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning d
>[2011/05/16 10:26:00.950595,  8] smbd/dosmode.c:666(dos_mode)
>  dos_mode returning d
>[2011/05/16 10:26:00.950615, 10] smbd/trans2.c:3873(store_file_unix_basic)
>  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
>[2011/05/16 10:26:00.950631,  4] smbd/trans2.c:3874(store_file_unix_basic)
>  store_file_unix_basic: st_mode=40777
>[2011/05/16 10:26:00.950648,  4] smbd/trans2.c:4724(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: 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=ffffff80, 17=60, 18=ffffff8b, 19=ffffffc4, 20=ffffffa2, 21=13, 22=ffffffcc, 23=1, 24=0, 25=ffffffd5, 26=ffffffa9, 27=1a, 28=ffffff9e, 29=13, 30=ffffffcc, 31=1, 32=ffffff80, 33=60, 34=ffffff8b, 35=ffffffc4, 36=ffffffa2, 37=13, 38=ffffffcc, 39=1, 40=fffffffb, 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=5e, 77=ffffffa2, 78=ffffffae, 79=2, 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=f, 93=0, 94=0, 95=0, 96=0, 97=0, 98=0, 99=0, 
>[2011/05/16 10:26:00.950763,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 100, useable_space = 16410
>[2011/05/16 10:26:00.950782,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 100, paramsize = 2, datasize = 100
>[2011/05/16 10:26:00.950798,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:26:00.950809,  5] lib/util.c:627(show_msg)
>  size=160
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=460
>  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
>[2011/05/16 10:26:00.950927, 10] ../lib/util/util.c:278(_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 80 60 8B   C4 A2 13 CC 01 00 D5 A9   ......`. ........
>  [0020] 1A 9E 13 CC 01 80 60 8B   C4 A2 13 CC 01 FB 03 00   ......`. ........
>  [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 5E A2 AE 02 00 00 00   00 FF 01 00 00 00 00 00   .^...... ........
>  [0060] 00 0F 00 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:26:10.947065, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 68
>[2011/05/16 10:26:10.947121,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x44
>[2011/05/16 10:26:10.947139,  3] smbd/process.c:1489(process_smb)
>  Transaction 25 of length 72 (0 toread)
>[2011/05/16 10:26:10.947156,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:26:10.947168,  5] lib/util.c:627(show_msg)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=0
>  smb_flg2=49153
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=461
>  smt_wct=15
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=  100 (0x64)
>  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]=    2 (0x2)
>  smb_vwv[10]=   66 (0x42)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    3 (0x3)
>  smb_bcc=3
>[2011/05/16 10:26:10.947306, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 01 02                                          ... 
>[2011/05/16 10:26:10.947334,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:26:10.947352,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:26:10.947372,  3] smbd/trans2.c:3463(call_trans2qfsinfo)
>  call_trans2qfsinfo: level = 513
>[2011/05/16 10:26:10.947394,  3] smbd/trans2.c:2906(smbd_do_qfsinfo)
>  smbd_do_qfsinfo: level = 513
>[2011/05/16 10:26:10.947578,  5] smbd/trans2.c:3286(smbd_do_qfsinfo)
>  smbd_do_qfsinfo : SMB_QUERY_POSIX_FS_INFO succsessful
>[2011/05/16 10:26:10.947599,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 0, data_sent_thistime = 56, useable_space = 16412
>[2011/05/16 10:26:10.947616,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 0, data_to_send = 56, paramsize = 0, datasize = 56
>[2011/05/16 10:26:10.947632,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:26:10.947642,  5] lib/util.c:627(show_msg)
>  size=112
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=461
>  smt_wct=10
>  smb_vwv[ 0]=    0 (0x0)
>  smb_vwv[ 1]=   56 (0x38)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    0 (0x0)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   56 (0x38)
>  smb_vwv[ 7]=   56 (0x38)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=57
>[2011/05/16 10:26:10.947801, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 10 00 00 00 10 00   00 82 92 53 0E 00 00 00   ........ ...S....
>  [0010] 00 F3 2E 3D 08 00 00 00   00 97 E1 82 07 00 00 00   ...=.... ........
>  [0020] 00 00 A0 A3 03 00 00 00   00 62 9E 97 03 00 00 00   ........ .b......
>  [0030] 00 A9 39 2C 52 00 00 00   00                       ..9,R... .
>[2011/05/16 10:26:10.947905,  4] smbd/trans2.c:3479(call_trans2qfsinfo)
>  SMBtrans2 info_level = 513
>[2011/05/16 10:26:10.948169, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
>  got smb length of 74
>[2011/05/16 10:26:10.948190,  6] smbd/process.c:1486(process_smb)
>  got message type 0x0 of len 0x4a
>[2011/05/16 10:26:10.948206,  3] smbd/process.c:1489(process_smb)
>  Transaction 26 of length 78 (0 toread)
>[2011/05/16 10:26:10.948222,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:26:10.948232,  5] lib/util.c:627(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=1064
>  smb_uid=100
>  smb_mid=462
>  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
>[2011/05/16 10:26:10.948371, 10] ../lib/util/util.c:278(_dump_data)
>  [0000] 00 00 02 00 00 00 00 00   00                       ........ .
>[2011/05/16 10:26:10.948398,  3] smbd/process.c:1298(switch_message)
>  switch message SMBtrans2 (pid 9822) conn 0xb7818128
>[2011/05/16 10:26:10.948415,  4] smbd/uid.c:257(change_to_user)
>  change_to_user: Skipping user change - already user
>[2011/05/16 10:26:10.948431,  3] smbd/trans2.c:5099(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512
>[2011/05/16 10:26:10.948453,  5] smbd/filename.c:169(unix_convert)
>  unix_convert called on file ""
>[2011/05/16 10:26:10.948471,  5] smbd/filename.c:203(unix_convert)
>  conversion finished "" -> .
>[2011/05/16 10:26:10.948487,  3] smbd/vfs.c:881(check_reduced_name)
>  check_reduced_name [.] [/home/alle]
>[2011/05/16 10:26:10.948508, 10] smbd/vfs.c:968(check_reduced_name)
>  check_reduced_name realpath [.] -> [/home/alle]
>[2011/05/16 10:26:10.948525,  3] smbd/vfs.c:1038(check_reduced_name)
>  check_reduced_name: . reduced to /home/alle
>[2011/05/16 10:26:10.948554, 10] locking/locking.c:926(fetch_share_mode_unlocked)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2011/05/16 10:26:10.948571,  3] smbd/trans2.c:5225(call_trans2qfilepathinfo)
>  call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0
>[2011/05/16 10:26:10.948588,  5] smbd/trans2.c:4197(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: . (fnum = -1) level=512 max_data=4000
>[2011/05/16 10:26:10.948605,  8] smbd/dosmode.c:613(dos_mode)
>  dos_mode: .
>[2011/05/16 10:26:10.948623,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
>  dos_mode_from_sbuf returning d
>[2011/05/16 10:26:10.948640,  8] smbd/dosmode.c:666(dos_mode)
>  dos_mode returning d
>[2011/05/16 10:26:10.948661, 10] smbd/trans2.c:3873(store_file_unix_basic)
>  store_file_unix_basic: SMB_QUERY_FILE_UNIX_BASIC
>[2011/05/16 10:26:10.948676,  4] smbd/trans2.c:3874(store_file_unix_basic)
>  store_file_unix_basic: st_mode=40777
>[2011/05/16 10:26:10.948693,  4] smbd/trans2.c:4724(smbd_do_qfilepathinfo)
>  smbd_do_qfilepathinfo: 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=ffffff80, 17=60, 18=ffffff8b, 19=ffffffc4, 20=ffffffa2, 21=13, 22=ffffffcc, 23=1, 24=0, 25=ffffffd5, 26=ffffffa9, 27=1a, 28=ffffff9e, 29=13, 30=ffffffcc, 31=1, 32=ffffff80, 33=60, 34=ffffff8b, 35=ffffffc4, 36=ffffffa2, 37=13, 38=ffffffcc, 39=1, 40=fffffffb, 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=5e, 77=ffffffa2, 78=ffffffae, 79=2, 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=f, 93=0, 94=0, 95=0, 96=0, 97=0, 98=0, 99=0, 
>[2011/05/16 10:26:10.948807,  9] smbd/trans2.c:927(send_trans2_replies)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 100, useable_space = 16410
>[2011/05/16 10:26:10.948825,  9] smbd/trans2.c:929(send_trans2_replies)
>  t2_rep: params_to_send = 2, data_to_send = 100, paramsize = 2, datasize = 100
>[2011/05/16 10:26:10.948841,  5] lib/util.c:617(show_msg)
>[2011/05/16 10:26:10.948851,  5] lib/util.c:627(show_msg)
>  size=160
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=128
>  smb_flg2=49155
>  smb_tid=1
>  smb_pid=1064
>  smb_uid=100
>  smb_mid=462
>  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
>[2011/05/16 10:26:10.948968, 10] ../lib/util/util.c:278(_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 80 60 8B   C4 A2 13 CC 01 00 D5 A9   ......`. ........
>  [0020] 1A 9E 13 CC 01 80 60 8B   C4 A2 13 CC 01 FB 03 00   ......`. ........
>  [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 5E A2 AE 02 00 00 00   00 FF 01 00 00 00 00 00   .^...... ........
>  [0060] 00 0F 00 00 00 00 00 00   00                       ........ .

Actions: View

Attachments on bug 8061: 6446