Attachment 4908 Details for Bug 6862 – level 10 log from smbd startup until first loop

level 10 log from smbd startup until first loop

smbd start to first rep.txt (text/plain), 161.63 KB, created by Brian Bayorgeon on 2009-10-31 01:09:56 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Brian Bayorgeon

Created: 2009-10-31 01:09:56 UTC

Size: 161.63 KB

patch

obsolete

>[2009/10/30 19:31:26,  3] smbd/server.c:exit_server_common(974)
>  Server exit (termination signal)
>[2009/10/30 19:31:29,  6] param/loadparm.c:lp_file_list_changed(6761)
>  lp_file_list_changed()
>  file /usr/local/etc/smb.conf -> /usr/local/etc/smb.conf  last mod_time: Fri Oct 30 19:31:19 2009
>[2009/10/30 19:31:29,  5] smbd/reply.c:reply_special(539)
>  init msg_type=0x81 msg_flags=0x0
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 133
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x85
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 0 of length 137 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=133
>  smb_com=0x72
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51267
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=0
>  smb_mid=0
>  smt_wct=0
>  smb_bcc=98
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 02 50 43 20 4E 45 54 57  4F 52 4B 20 50 52 4F 47  .PC NETW ORK PROG
>  [010] 52 41 4D 20 31 2E 30 00  02 4C 41 4E 4D 41 4E 31  RAM 1.0. .LANMAN1
>  [020] 2E 30 00 02 57 69 6E 64  6F 77 73 20 66 6F 72 20  .0..Wind ows for 
>  [030] 57 6F 72 6B 67 72 6F 75  70 73 20 33 2E 31 61 00  Workgrou ps 3.1a.
>  [040] 02 4C 4D 31 2E 32 58 30  30 32 00 02 4C 41 4E 4D  .LM1.2X0 02..LANM
>  [050] 41 4E 32 2E 31 00 02 4E  54 20 4C 4D 20 30 2E 31  AN2.1..N T LM 0.1
>  [060] 32 00                                             2. 
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBnegprot (pid 16142) conn 0x0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  5] smbd/uid.c:change_to_root_user(370)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/10/30 19:31:29,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [PC NETWORK PROGRAM 1.0]
>[2009/10/30 19:31:29,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [LANMAN1.0]
>[2009/10/30 19:31:29,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [Windows for Workgroups 3.1a]
>[2009/10/30 19:31:29,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [LM1.2X002]
>[2009/10/30 19:31:29,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [LANMAN2.1]
>[2009/10/30 19:31:29,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [NT LM 0.12]
>[2009/10/30 19:31:29, 10] lib/util.c:set_remote_arch(2208)
>  set_remote_arch: Client arch is 'Win2K'
>[2009/10/30 19:31:29,  6] param/loadparm.c:lp_file_list_changed(6761)
>  lp_file_list_changed()
>  file /usr/local/etc/smb.conf -> /usr/local/etc/smb.conf  last mod_time: Fri Oct 30 19:31:19 2009
>  
>[2009/10/30 19:31:29,  5] smbd/connection.c:claim_connection(142)
>  claiming []
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
>  Locking key 0E3F0000FFFFFFFF0000
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
>  Allocated locked data 0x0x20c596b0
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
>  Unlocking key 0E3F0000FFFFFFFF0000
>[2009/10/30 19:31:29,  6] param/loadparm.c:lp_file_list_changed(6761)
>  lp_file_list_changed()
>  file /usr/local/etc/smb.conf -> /usr/local/etc/smb.conf  last mod_time: Fri Oct 30 19:31:19 2009
>  
>[2009/10/30 19:31:29, 10] lib/util.c:set_remote_arch(2208)
>  set_remote_arch: Client arch is 'Vista'
>[2009/10/30 19:31:29,  3] smbd/negprot.c:reply_nt1(392)
>  using SPNEGO
>[2009/10/30 19:31:29,  3] smbd/negprot.c:reply_negprot(674)
>  Selected protocol NT LM 0.12
>[2009/10/30 19:31:29,  5] smbd/negprot.c:reply_negprot(681)
>  negprot index=5
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=127
>  smb_com=0x72
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51267
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=0
>  smb_mid=0
>  smt_wct=17
>  smb_vwv[ 0]=    5 (0x5)
>  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]= 3584 (0xE00)
>  smb_vwv[ 8]=   63 (0x3F)
>  smb_vwv[ 9]=64768 (0xFD00)
>  smb_vwv[10]=33011 (0x80F3)
>  smb_vwv[11]=32896 (0x8080)
>  smb_vwv[12]=49158 (0xC006)
>  smb_vwv[13]=49532 (0xC17C)
>  smb_vwv[14]=51801 (0xCA59)
>  smb_vwv[15]=11265 (0x2C01)
>  smb_vwv[16]=    1 (0x1)
>  smb_bcc=58
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 6F 6C 64 6A 75 6E 6B 00  00 00 00 00 00 00 00 00  oldjunk. ........
>  [010] 60 28 06 06 2B 06 01 05  05 02 A0 1E 30 1C A0 0E  `(..+... ....0...
>  [020] 30 0C 06 0A 2B 06 01 04  01 82 37 02 02 0A A3 0A  0...+... ..7.....
>  [030] 30 08 A0 06 1B 04 4E 4F  4E 45                    0.....NO NE
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 138
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x8a
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 1 of length 142 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=138
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=65535
>  smb_pid=65279
>  smb_uid=0
>  smb_mid=57408
>  smt_wct=12
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=16644 (0x4104)
>  smb_vwv[ 3]=   50 (0x32)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=   74 (0x4A)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_vwv[10]=  212 (0xD4)
>  smb_vwv[11]=40960 (0xA000)
>  smb_bcc=79
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 60 48 06 06 2B 06 01 05  05 02 A0 3E 30 3C A0 0E  `H..+... ...>0<..
>  [010] 30 0C 06 0A 2B 06 01 04  01 82 37 02 02 0A A2 2A  0...+... ..7....*
>  [020] 04 28 4E 54 4C 4D 53 53  50 00 01 00 00 00 97 82  .(NTLMSS P.......
>  [030] 08 E2 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
>  [040] 00 00 06 00 72 17 00 00  00 0F 00 00 00 00 00     ....r... .......
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBsesssetupX (pid 16142) conn 0x0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  5] smbd/uid.c:change_to_root_user(370)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/10/30 19:31:29,  3] smbd/sesssetup.c:reply_sesssetup_and_X(1412)
>  wct=12 flg2=0xc807
>[2009/10/30 19:31:29,  2] smbd/sesssetup.c:setup_new_vc_session(1368)
>  setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources.
>[2009/10/30 19:31:29,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1175)
>  Doing spnego session setup
>[2009/10/30 19:31:29,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1210)
>  NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
>[2009/10/30 19:31:29, 10] smbd/password.c:register_initial_vuid(192)
>  register_initial_vuid: allocated vuid = 100
>[2009/10/30 19:31:29, 10] smbd/sesssetup.c:check_spnego_blob_complete(1123)
>  check_spnego_blob_complete: needed_len = 74, pblob->length = 74
>[2009/10/30 19:31:29,  5] smbd/sesssetup.c:parse_spnego_mechanisms(751)
>  parse_spnego_mechanisms: Got OID 1 3 6 1 4 1 311 2 2 10
>[2009/10/30 19:31:29,  3] smbd/sesssetup.c:reply_spnego_negotiate(802)
>  reply_spnego_negotiate: Got secblob of size 40
>[2009/10/30 19:31:29,  5] auth/auth.c:make_auth_context_subsystem(491)
>  Making default auth method list for standalone security=user, encrypt passwords = yes
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(46)
>  Attempting to register auth backend sam
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(58)
>  Successfully added auth method 'sam'
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(46)
>  Attempting to register auth backend sam_ignoredomain
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(58)
>  Successfully added auth method 'sam_ignoredomain'
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(46)
>  Attempting to register auth backend unix
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(58)
>  Successfully added auth method 'unix'
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(46)
>  Attempting to register auth backend winbind
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(58)
>  Successfully added auth method 'winbind'
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(46)
>  Attempting to register auth backend smbserver
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(58)
>  Successfully added auth method 'smbserver'
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(46)
>  Attempting to register auth backend trustdomain
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(58)
>  Successfully added auth method 'trustdomain'
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(46)
>  Attempting to register auth backend ntdomain
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(58)
>  Successfully added auth method 'ntdomain'
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(46)
>  Attempting to register auth backend guest
>[2009/10/30 19:31:29,  5] auth/auth.c:smb_register_auth(58)
>  Successfully added auth method 'guest'
>[2009/10/30 19:31:29,  5] auth/auth.c:load_auth_module(387)
>  load_auth_module: Attempting to find an auth method to match guest
>[2009/10/30 19:31:29,  5] auth/auth.c:load_auth_module(412)
>  load_auth_module: auth method guest has a valid init
>[2009/10/30 19:31:29,  5] auth/auth.c:load_auth_module(387)
>  load_auth_module: Attempting to find an auth method to match sam
>[2009/10/30 19:31:29,  5] auth/auth.c:load_auth_module(412)
>  load_auth_module: auth method sam has a valid init
>[2009/10/30 19:31:29,  3] libsmb/ntlmssp.c:debug_ntlmssp_flags(62)
>  Got NTLMSSP neg_flags=0xe2088297
>    NTLMSSP_NEGOTIATE_UNICODE
>    NTLMSSP_NEGOTIATE_OEM
>    NTLMSSP_REQUEST_TARGET
>    NTLMSSP_NEGOTIATE_SIGN
>    NTLMSSP_NEGOTIATE_LM_KEY
>    NTLMSSP_NEGOTIATE_NTLM
>    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
>    NTLMSSP_NEGOTIATE_NTLM2
>    NTLMSSP_NEGOTIATE_VERSION
>    NTLMSSP_NEGOTIATE_128
>    NTLMSSP_NEGOTIATE_KEY_EXCH
>    NTLMSSP_NEGOTIATE_56
>[2009/10/30 19:31:29,  5] auth/auth.c:get_ntlm_challenge(96)
>  auth_get_challenge: module guest did not want to specify a challenge
>[2009/10/30 19:31:29,  5] auth/auth.c:get_ntlm_challenge(96)
>  auth_get_challenge: module sam did not want to specify a challenge
>[2009/10/30 19:31:29,  5] auth/auth.c:get_ntlm_challenge(136)
>  auth_context challenge created by random
>[2009/10/30 19:31:29,  5] auth/auth.c:get_ntlm_challenge(137)
>  challenge is: 
>[2009/10/30 19:31:29,  5] lib/util.c:dump_data(2233)
>  [000] 84 80 6C 0D 99 F0 F6 C2                           ..l..... 
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=270
>  smb_com=0x73
>  smb_rcls=22
>  smb_reh=0
>  smb_err=49152
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=65535
>  smb_pid=65279
>  smb_uid=100
>  smb_mid=57408
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=  181 (0xB5)
>  smb_bcc=227
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] A1 81 B2 30 81 AF A0 03  0A 01 01 A1 0C 06 0A 2B  ...0.... .......+
>  [010] 06 01 04 01 82 37 02 02  0A A2 81 99 04 81 96 4E  .....7.. .......N
>  [020] 54 4C 4D 53 53 50 00 02  00 00 00 0E 00 0E 00 30  TLMSSP.. .......0
>  [030] 00 00 00 15 82 8A E2 84  80 6C 0D 99 F0 F6 C2 00  ........ .l......
>  [040] 00 00 00 00 00 00 00 58  00 58 00 3E 00 00 00 4F  .......X .X.>...O
>  [050] 00 4C 00 44 00 4A 00 55  00 4E 00 4B 00 02 00 0E  .L.D.J.U .N.K....
>  [060] 00 4F 00 4C 00 44 00 4A  00 55 00 4E 00 4B 00 01  .O.L.D.J .U.N.K..
>  [070] 00 0E 00 4F 00 4C 00 44  00 4A 00 55 00 4E 00 4B  ...O.L.D .J.U.N.K
>  [080] 00 04 00 0C 00 66 00 76  00 2E 00 6E 00 65 00 74  .....f.v ...n.e.t
>  [090] 00 03 00 1C 00 6F 00 6C  00 64 00 6A 00 75 00 6E  .....o.l .d.j.u.n
>  [0A0] 00 6B 00 2E 00 66 00 76  00 2E 00 6E 00 65 00 74  .k...f.v ...n.e.t
>  [0B0] 00 00 00 00 00 55 00 6E  00 69 00 78 00 00 00 53  .....U.n .i.x...S
>  [0C0] 00 61 00 6D 00 62 00 61  00 20 00 33 00 2E 00 33  .a.m.b.a . .3...3
>  [0D0] 00 2E 00 38 00 00 00 58  00 4E 00 45 00 54 00 32  ...8...X .N.E.T.2
>  [0E0] 00 00 00                                          ... 
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 448
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x1c0
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 2 of length 452 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=448
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=65535
>  smb_pid=65279
>  smb_uid=100
>  smb_mid=57472
>  smt_wct=12
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=16644 (0x4104)
>  smb_vwv[ 3]=   50 (0x32)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=  384 (0x180)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_vwv[10]=  212 (0xD4)
>  smb_vwv[11]=40960 (0xA000)
>  smb_bcc=389
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] A1 82 01 7C 30 82 01 78  A2 82 01 74 04 82 01 70  ...|0..x ...t...p
>  [010] 4E 54 4C 4D 53 53 50 00  03 00 00 00 18 00 18 00  NTLMSSP. ........
>  [020] 8C 00 00 00 BC 00 BC 00  A4 00 00 00 16 00 16 00  ........ ........
>  [030] 58 00 00 00 08 00 08 00  6E 00 00 00 16 00 16 00  X....... n.......
>  [040] 76 00 00 00 10 00 10 00  60 01 00 00 15 82 88 E2  v....... `.......
>  [050] 06 00 72 17 00 00 00 0F  8A ED 86 11 29 C7 19 B6  ..r..... ....)...
>  [060] D7 5C 19 09 FD DB B8 72  44 00 61 00 64 00 73 00  .\.....r D.a.d.s.
>  [070] 44 00 65 00 73 00 6B 00  74 00 6F 00 70 00 72 00  D.e.s.k. t.o.p.r.
>  [080] 6F 00 6F 00 74 00 44 00  41 00 44 00 53 00 44 00  o.o.t.D. A.D.S.D.
>  [090] 45 00 53 00 4B 00 54 00  4F 00 50 00 AC 64 C0 48  E.S.K.T. O.P..d.H
>  [0A0] AE 32 1F 1E 33 77 43 8B  CC EF 28 C2 02 AA DE A3  .2..3wC. ..(.....
>  [0B0] EB 8A A1 BD F0 52 CF 7A  8A 89 B3 65 2E E0 71 38  .....R.z ...e..q8
>  [0C0] 59 9C DE 68 01 01 00 00  00 00 00 00 60 05 5D 79  Y..h.... ....`.]y
>  [0D0] C1 59 CA 01 02 AA DE A3  EB 8A A1 BD 00 00 00 00  .Y...... ........
>  [0E0] 02 00 0E 00 4F 00 4C 00  44 00 4A 00 55 00 4E 00  ....O.L. D.J.U.N.
>  [0F0] 4B 00 01 00 0E 00 4F 00  4C 00 44 00 4A 00 55 00  K.....O. L.D.J.U.
>  [100] 4E 00 4B 00 04 00 0C 00  66 00 76 00 2E 00 6E 00  N.K..... f.v...n.
>  [110] 65 00 74 00 03 00 1C 00  6F 00 6C 00 64 00 6A 00  e.t..... o.l.d.j.
>  [120] 75 00 6E 00 6B 00 2E 00  66 00 76 00 2E 00 6E 00  u.n.k... f.v...n.
>  [130] 65 00 74 00 08 00 30 00  30 00 00 00 00 00 00 00  e.t...0. 0.......
>  [140] 01 00 00 00 00 20 00 00  83 07 6B 48 62 E2 C9 69  ..... .. ..kHb..i
>  [150] 3C 97 0D EC 0F E0 3A 4E  4A 6D A4 2B 3A 1D 53 73  <.....:N Jm.+:.Ss
>  [160] F1 EE 22 5A 66 35 79 4D  00 00 00 00 00 00 00 00  .."Zf5yM ........
>  [170] E1 CB 1D 31 39 59 43 93  5F C0 9E B3 68 6E E2 5E  ...19YC. _...hn.^
>  [180] 00 00 00 00 00                                    ..... 
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBsesssetupX (pid 16142) conn 0x0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  5] smbd/uid.c:change_to_root_user(370)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/10/30 19:31:29,  3] smbd/sesssetup.c:reply_sesssetup_and_X(1412)
>  wct=12 flg2=0xc807
>[2009/10/30 19:31:29,  2] smbd/sesssetup.c:setup_new_vc_session(1368)
>  setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources.
>[2009/10/30 19:31:29,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1175)
>  Doing spnego session setup
>[2009/10/30 19:31:29,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1210)
>  NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
>[2009/10/30 19:31:29, 10] smbd/sesssetup.c:check_spnego_blob_complete(1123)
>  check_spnego_blob_complete: needed_len = 384, pblob->length = 384
>[2009/10/30 19:31:29,  3] libsmb/ntlmssp.c:ntlmssp_server_auth(747)
>  Got user=[root] domain=[DadsDesktop] workstation=[DADSDESKTOP] len1=24 len2=188
>[2009/10/30 19:31:29,  6] param/loadparm.c:lp_file_list_changed(6761)
>  lp_file_list_changed()
>  file /usr/local/etc/smb.conf -> /usr/local/etc/smb.conf  last mod_time: Fri Oct 30 19:31:19 2009
>  
>[2009/10/30 19:31:29,  5] auth/auth_util.c:make_user_info_map(206)
>  make_user_info_map: Mapping user [DadsDesktop]\[root] from workstation [DADSDESKTOP]
>[2009/10/30 19:31:29,  5] auth/auth_util.c:make_user_info(120)
>  attempting to make a user_info for root (root)
>[2009/10/30 19:31:29,  5] auth/auth_util.c:make_user_info(130)
>  making strings for root's user_info struct
>[2009/10/30 19:31:29,  5] auth/auth_util.c:make_user_info(162)
>  making blobs for root's user_info struct
>[2009/10/30 19:31:29, 10] auth/auth_util.c:make_user_info(180)
>  made an encrypted user_info for root (root)
>[2009/10/30 19:31:29,  3] auth/auth.c:check_ntlm_password(220)
>  check_ntlm_password:  Checking password for unmapped user [DadsDesktop]\[root]@[DADSDESKTOP] with the new password interface
>[2009/10/30 19:31:29,  3] auth/auth.c:check_ntlm_password(223)
>  check_ntlm_password:  mapped user is: [OLDJUNK]\[root]@[DADSDESKTOP]
>[2009/10/30 19:31:29, 10] auth/auth.c:check_ntlm_password(232)
>  check_ntlm_password: auth_context challenge created by random
>[2009/10/30 19:31:29, 10] auth/auth.c:check_ntlm_password(234)
>  challenge is: 
>[2009/10/30 19:31:29,  5] lib/util.c:dump_data(2233)
>  [000] 84 80 6C 0D 99 F0 F6 C2                           ..l..... 
>[2009/10/30 19:31:29, 10] auth/auth.c:check_ntlm_password(260)
>  check_ntlm_password: guest had nothing to say
>[2009/10/30 19:31:29,  8] lib/util.c:is_myname(2108)
>  is_myname("OLDJUNK") returns 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_username(572)
>  pdb_set_username: setting username root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_domain(595)
>  pdb_set_domain: setting domain OLDJUNK, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_nt_username(618)
>  pdb_set_nt_username: setting nt username , was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_fullname(641)
>  pdb_set_full_name: setting full name Charlie &, was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_homedir(734)
>  pdb_set_homedir: setting home dir \\oldjunk\root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(710)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_logon_script(664)
>  pdb_set_logon_script: setting logon script , was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_profile_path(687)
>  pdb_set_profile_path: setting profile path \\oldjunk\root\profile, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_workstations(777)
>  pdb_set_workstations: setting workstations , was 
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_user_sid(501)
>  pdb_set_user_sid: setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001
>[2009/10/30 19:31:29, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001 from rid 1001
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: maximum password age, val: -1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_alloc(133)
>  Finding user root
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_internals(77)
>  Trying _Get_Pwnam(), username as lowercase is root
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_internals(110)
>  Get_Pwnam_internals did find user [root]!
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_username(572)
>  pdb_set_username: setting username root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_domain(595)
>  pdb_set_domain: setting domain OLDJUNK, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_nt_username(618)
>  pdb_set_nt_username: setting nt username , was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_fullname(641)
>  pdb_set_full_name: setting full name Charlie &, was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_homedir(734)
>  pdb_set_homedir: setting home dir \\oldjunk\root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(710)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_logon_script(664)
>  pdb_set_logon_script: setting logon script , was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_profile_path(687)
>  pdb_set_profile_path: setting profile path \\oldjunk\root\profile, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_workstations(777)
>  pdb_set_workstations: setting workstations , was 
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_user_sid(501)
>  pdb_set_user_sid: setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001
>[2009/10/30 19:31:29, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001 from rid 1001
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  9] passdb/passdb.c:pdb_update_autolock_flag(2112)
>  pdb_update_autolock_flag: Account root not autolocked, no check needed
>[2009/10/30 19:31:29,  4] libsmb/ntlm_check.c:ntlm_password_check(290)
>  ntlm_password_check: Checking NTLMv2 password with domain [DadsDesktop]
>[2009/10/30 19:31:29,  4] auth/auth_sam.c:sam_account_ok(137)
>  sam_account_ok: Checking SMB password for user root
>[2009/10/30 19:31:29,  5] auth/auth_sam.c:logon_hours_ok(119)
>  logon_hours_ok: user root allowed to logon at this time (Sat Oct 31 00:31:29 2009
>  )
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: maximum password age, val: -1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/system_smbd.c:sys_getgrouplist(122)
>  sys_getgrouplist: user [root]
>[2009/10/30 19:31:29, 10] lib/gencache.c:gencache_get(194)
>  Cache entry with key = IDMAP/GID2SID/5 couldn't be found
>[2009/10/30 19:31:29,  5] passdb/lookup_sid.c:gid_to_sid(1378)
>  gid_to_sid: winbind failed to find a sid for gid 5
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29, 10] passdb/lookup_sid.c:legacy_gid_to_sid(1173)
>  LEGACY: gid 5 -> sid S-1-22-2-5
>[2009/10/30 19:31:29,  5] auth/auth_util.c:make_server_info_sam(635)
>  make_server_info_sam: made server info for user root -> root
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] auth/auth.c:check_ntlm_password(269)
>  check_ntlm_password: sam authentication for user [root] succeeded
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  5] auth/auth.c:check_ntlm_password(295)
>  check_ntlm_password:  PAM Account for user [root] succeeded
>[2009/10/30 19:31:29,  2] auth/auth.c:check_ntlm_password(308)
>  check_ntlm_password:  authentication for user [root] -> [root] -> [root] succeeded
>[2009/10/30 19:31:29,  5] auth/auth_util.c:free_user_info(2103)
>  attempting to free (and zero) a user_info structure
>[2009/10/30 19:31:29, 10] auth/auth_util.c:free_user_info(2107)
>  structure was created for root
>[2009/10/30 19:31:29, 10] auth/token_util.c:create_local_nt_token(353)
>  Create local NT token for S-1-5-21-3710583579-4186145173-3854701597-1001
>[2009/10/30 19:31:29, 10] lib/gencache.c:gencache_get(194)
>  Cache entry with key = IDMAP/SID2GID/S-1-5-32-544 couldn't be found
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1247)
>  LEGACY: mapping failed for sid S-1-5-32-544
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  3] auth/token_util.c:create_local_nt_token(433)
>  Failed to fetch domain sid for XNET2
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] lib/gencache.c:gencache_get(194)
>  Cache entry with key = IDMAP/SID2GID/S-1-5-32-545 couldn't be found
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1247)
>  LEGACY: mapping failed for sid S-1-5-32-545
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  3] auth/token_util.c:create_local_nt_token(464)
>  Failed to fetch domain sid for XNET2
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-21-3710583579-4186145173-3854701597-1001]
>[2009/10/30 19:31:29,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-22-2-0]
>[2009/10/30 19:31:29,  5] lib/privileges.c:get_privileges_for_sids(128)
>  get_privileges_for_sids: sid = S-1-1-0
>  Privilege set:
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2009/10/30 19:31:29,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2009/10/30 19:31:29,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-11]
>[2009/10/30 19:31:29,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-22-2-5]
>[2009/10/30 19:31:29, 10] passdb/lookup_sid.c:sid_to_gid(1482)
>  sid S-1-22-2-0 -> gid 0
>[2009/10/30 19:31:29, 10] lib/gencache.c:gencache_get(194)
>  Cache entry with key = IDMAP/SID2GID/S-1-1-0 couldn't be found
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1247)
>  LEGACY: mapping failed for sid S-1-1-0
>[2009/10/30 19:31:29, 10] auth/auth_util.c:create_local_token(739)
>  Could not convert SID S-1-1-0 to gid, ignoring it
>[2009/10/30 19:31:29, 10] lib/gencache.c:gencache_get(194)
>  Cache entry with key = IDMAP/SID2GID/S-1-5-2 couldn't be found
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1247)
>  LEGACY: mapping failed for sid S-1-5-2
>[2009/10/30 19:31:29, 10] auth/auth_util.c:create_local_token(739)
>  Could not convert SID S-1-5-2 to gid, ignoring it
>[2009/10/30 19:31:29, 10] lib/gencache.c:gencache_get(194)
>  Cache entry with key = IDMAP/SID2GID/S-1-5-11 couldn't be found
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1247)
>  LEGACY: mapping failed for sid S-1-5-11
>[2009/10/30 19:31:29, 10] auth/auth_util.c:create_local_token(739)
>  Could not convert SID S-1-5-11 to gid, ignoring it
>[2009/10/30 19:31:29, 10] passdb/lookup_sid.c:sid_to_gid(1482)
>  sid S-1-22-2-5 -> gid 5
>[2009/10/30 19:31:29, 10] auth/token_util.c:debug_nt_user_token(528)
>  NT user token of user S-1-5-21-3710583579-4186145173-3854701597-1001
>  contains 7 SIDs
>  SID[  0]: S-1-5-21-3710583579-4186145173-3854701597-1001
>  SID[  1]: S-1-22-2-0
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-11
>  SID[  5]: S-1-22-2-5
>  SID[  6]: S-1-22-1-0
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2009/10/30 19:31:29, 10] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 2 supplementary groups
>  Group[  0]: 0
>  Group[  1]: 5
>[2009/10/30 19:31:29, 10] auth/auth_ntlmssp.c:auth_ntlmssp_check_password(137)
>  Got NT session key of length 16
>[2009/10/30 19:31:29, 10] libsmb/ntlmssp.c:ntlmssp_server_auth(850)
>  ntlmssp_server_auth: Using unmodified nt session key.
>[2009/10/30 19:31:29,  3] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(337)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2009/10/30 19:31:29,  3] libsmb/ntlmssp.c:debug_ntlmssp_flags(62)
>  Got NTLMSSP neg_flags=0xe2088215
>    NTLMSSP_NEGOTIATE_UNICODE
>    NTLMSSP_REQUEST_TARGET
>    NTLMSSP_NEGOTIATE_SIGN
>    NTLMSSP_NEGOTIATE_NTLM
>    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
>    NTLMSSP_NEGOTIATE_NTLM2
>    NTLMSSP_NEGOTIATE_VERSION
>    NTLMSSP_NEGOTIATE_128
>    NTLMSSP_NEGOTIATE_KEY_EXCH
>    NTLMSSP_NEGOTIATE_56
>[2009/10/30 19:31:29, 10] smbd/password.c:register_existing_vuid(285)
>  register_existing_vuid: (0,0) root root OLDJUNK guest=0
>[2009/10/30 19:31:29,  3] smbd/password.c:register_existing_vuid(289)
>  register_existing_vuid: User name: root	Real name: Charlie &
>[2009/10/30 19:31:29,  3] smbd/password.c:register_existing_vuid(299)
>  register_existing_vuid: UNIX uid 0 is UNIX user root, and will be vuid 100
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
>  Locking key 49442F31363134322F31
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
>  Allocated locked data 0x0x20c17c10
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
>  Unlocking key 49442F31363134322F31
>[2009/10/30 19:31:29,  7] param/loadparm.c:lp_servicenumber(9078)
>  lp_servicenumber: couldn't find root
>[2009/10/30 19:31:29,  3] smbd/password.c:register_homes_share(231)
>  Adding homes service for user 'root' using home directory: '/root'
>[2009/10/30 19:31:29,  8] param/loadparm.c:add_a_service(5808)
>  add_a_service: Creating snum = 7 for root
>[2009/10/30 19:31:29, 10] param/loadparm.c:hash_a_service(5855)
>  hash_a_service: hashing index 7 for service name root
>[2009/10/30 19:31:29,  3] param/loadparm.c:lp_add_home(5909)
>  adding home's share [root] for user 'root' at '/usr/home/%u/Documents'
>[2009/10/30 19:31:29,  6] param/loadparm.c:lp_file_list_changed(6761)
>  lp_file_list_changed()
>  file /usr/local/etc/smb.conf -> /usr/local/etc/smb.conf  last mod_time: Fri Oct 30 19:31:19 2009
>  
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=98
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=65535
>  smb_pid=65279
>  smb_uid=100
>  smb_mid=57472
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    9 (0x9)
>  smb_bcc=55
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] A1 07 30 05 A0 03 0A 01  00 55 00 6E 00 69 00 78  ..0..... .U.n.i.x
>  [010] 00 00 00 53 00 61 00 6D  00 62 00 61 00 20 00 33  ...S.a.m .b.a. .3
>  [020] 00 2E 00 33 00 2E 00 38  00 00 00 58 00 4E 00 45  ...3...8 ...X.N.E
>  [030] 00 54 00 32 00 00 00                              .T.2... 
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 80
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x50
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 3 of length 84 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=80
>  smb_com=0x75
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=100
>  smb_mid=57536
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=   80 (0x50)
>  smb_vwv[ 2]=    8 (0x8)
>  smb_vwv[ 3]=    1 (0x1)
>  smb_bcc=37
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 5C 00 5C 00 4F 00 4C  00 44 00 4A 00 55 00 4E  .\.\.O.L .D.J.U.N
>  [010] 00 4B 00 5C 00 52 00 4F  00 4F 00 54 00 00 00 3F  .K.\.R.O .O.T...?
>  [020] 3F 3F 3F 3F 00                                    ????. 
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtconX (pid 16142) conn 0x0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  5] smbd/uid.c:change_to_root_user(370)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/10/30 19:31:29,  4] smbd/reply.c:reply_tcon_and_X(720)
>  Client requested device type [?????] for share [ROOT]
>[2009/10/30 19:31:29,  5] smbd/service.c:make_connection(1269)
>  making a connection to 'homes' service [ROOT] created at session setup time
>[2009/10/30 19:31:29,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (10.0.2.)
>[2009/10/30 19:31:29,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2009/10/30 19:31:29,  2] lib/access.c:check_access(406)
>  Allowed connection from 10.0.2.149 (10.0.2.149)
>[2009/10/30 19:31:29,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid root does not start with 'S-'.
>[2009/10/30 19:31:29, 10] passdb/lookup_sid.c:lookup_name(69)
>  lookup_name: OLDJUNK\root => OLDJUNK (domain), root (name)
>[2009/10/30 19:31:29, 10] passdb/lookup_sid.c:lookup_name(70)
>  lookup_name: flags = 0x073
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_username(572)
>  pdb_set_username: setting username root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_domain(595)
>  pdb_set_domain: setting domain OLDJUNK, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_nt_username(618)
>  pdb_set_nt_username: setting nt username , was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_fullname(641)
>  pdb_set_full_name: setting full name Charlie &, was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_homedir(734)
>  pdb_set_homedir: setting home dir \\oldjunk\root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(710)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_logon_script(664)
>  pdb_set_logon_script: setting logon script , was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_profile_path(687)
>  pdb_set_profile_path: setting profile path \\oldjunk\root\profile, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_workstations(777)
>  pdb_set_workstations: setting workstations , was 
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_user_sid(501)
>  pdb_set_user_sid: setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001
>[2009/10/30 19:31:29, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001 from rid 1001
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: maximum password age, val: -1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_alloc(133)
>  Finding user root
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_internals(77)
>  Trying _Get_Pwnam(), username as lowercase is root
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_internals(110)
>  Get_Pwnam_internals did find user [root]!
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_username(572)
>  pdb_set_username: setting username root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_domain(595)
>  pdb_set_domain: setting domain OLDJUNK, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_nt_username(618)
>  pdb_set_nt_username: setting nt username , was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_fullname(641)
>  pdb_set_full_name: setting full name Charlie &, was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_homedir(734)
>  pdb_set_homedir: setting home dir \\oldjunk\root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(710)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_logon_script(664)
>  pdb_set_logon_script: setting logon script , was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_profile_path(687)
>  pdb_set_profile_path: setting profile path \\oldjunk\root\profile, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_workstations(777)
>  pdb_set_workstations: setting workstations , was 
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_user_sid(501)
>  pdb_set_user_sid: setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001
>[2009/10/30 19:31:29, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001 from rid 1001
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] smbd/share_access.c:user_ok_token(234)
>  user_ok_token: share root is ok for unix user root
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: maximum password age, val: -1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_username(572)
>  pdb_set_username: setting username root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_domain(595)
>  pdb_set_domain: setting domain OLDJUNK, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_nt_username(618)
>  pdb_set_nt_username: setting nt username , was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_fullname(641)
>  pdb_set_full_name: setting full name Charlie &, was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_homedir(734)
>  pdb_set_homedir: setting home dir \\oldjunk\root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(710)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_logon_script(664)
>  pdb_set_logon_script: setting logon script , was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_profile_path(687)
>  pdb_set_profile_path: setting profile path \\oldjunk\root\profile, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_workstations(777)
>  pdb_set_workstations: setting workstations , was 
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_user_sid(501)
>  pdb_set_user_sid: setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001
>[2009/10/30 19:31:29, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001 from rid 1001
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_alloc(133)
>  Finding user root
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_internals(77)
>  Trying _Get_Pwnam(), username as lowercase is root
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_internals(110)
>  Get_Pwnam_internals did find user [root]!
>[2009/10/30 19:31:29, 10] smbd/service.c:set_conn_connectpath(161)
>  set_conn_connectpath: service root, connectpath = /usr/home/root/Documents
>[2009/10/30 19:31:29,  3] smbd/service.c:make_connection_snum(879)
>  Connect path is '/usr/home/root/Documents' for service [root]
>[2009/10/30 19:31:29, 10] lib/util_seaccess.c:se_map_generic(58)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2009/10/30 19:31:29,  3] smbd/vfs.c:vfs_init_default(96)
>  Initialising default vfs hooks
>[2009/10/30 19:31:29, 10] smbd/vfs.c:vfs_find_backend_entry(48)
>  vfs_find_backend_entry called for /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:smb_register_vfs(86)
>  Successfully added vfs backend '/[Default VFS]/'
>[2009/10/30 19:31:29, 10] smbd/vfs.c:vfs_find_backend_entry(48)
>  vfs_find_backend_entry called for posixacl
>[2009/10/30 19:31:29,  5] smbd/vfs.c:smb_register_vfs(86)
>  Successfully added vfs backend 'posixacl'
>[2009/10/30 19:31:29,  3] smbd/vfs.c:vfs_init_custom(130)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2009/10/30 19:31:29, 10] smbd/vfs.c:vfs_find_backend_entry(48)
>  vfs_find_backend_entry called for /[Default VFS]/
>  Successfully loaded vfs module [/[Default VFS]/] with the new modules system
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #0 (type 0, layer 0)
>  Making operation type 0 opaque [module /[Default VFS]/]
>  Accepting operation type 0 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #1 (type 1, layer 0)
>  Making operation type 1 opaque [module /[Default VFS]/]
>  Accepting operation type 1 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #2 (type 2, layer 0)
>  Making operation type 2 opaque [module /[Default VFS]/]
>  Accepting operation type 2 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #3 (type 3, layer 0)
>  Making operation type 3 opaque [module /[Default VFS]/]
>  Accepting operation type 3 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #4 (type 4, layer 0)
>  Making operation type 4 opaque [module /[Default VFS]/]
>  Accepting operation type 4 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #5 (type 5, layer 0)
>  Making operation type 5 opaque [module /[Default VFS]/]
>  Accepting operation type 5 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #6 (type 6, layer 0)
>  Making operation type 6 opaque [module /[Default VFS]/]
>  Accepting operation type 6 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #7 (type 7, layer 0)
>  Making operation type 7 opaque [module /[Default VFS]/]
>  Accepting operation type 7 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #8 (type 8, layer 0)
>  Making operation type 8 opaque [module /[Default VFS]/]
>  Accepting operation type 8 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #9 (type 9, layer 0)
>  Making operation type 9 opaque [module /[Default VFS]/]
>  Accepting operation type 9 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #10 (type 10, layer 0)
>  Making operation type 10 opaque [module /[Default VFS]/]
>  Accepting operation type 10 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #11 (type 11, layer 0)
>  Making operation type 11 opaque [module /[Default VFS]/]
>  Accepting operation type 11 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #12 (type 12, layer 0)
>  Making operation type 12 opaque [module /[Default VFS]/]
>  Accepting operation type 12 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #13 (type 13, layer 0)
>  Making operation type 13 opaque [module /[Default VFS]/]
>  Accepting operation type 13 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #14 (type 14, layer 0)
>  Making operation type 14 opaque [module /[Default VFS]/]
>  Accepting operation type 14 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #15 (type 15, layer 0)
>  Making operation type 15 opaque [module /[Default VFS]/]
>  Accepting operation type 15 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #16 (type 16, layer 0)
>  Making operation type 16 opaque [module /[Default VFS]/]
>  Accepting operation type 16 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #17 (type 17, layer 0)
>  Making operation type 17 opaque [module /[Default VFS]/]
>  Accepting operation type 17 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #18 (type 18, layer 0)
>  Making operation type 18 opaque [module /[Default VFS]/]
>  Accepting operation type 18 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #19 (type 19, layer 0)
>  Making operation type 19 opaque [module /[Default VFS]/]
>  Accepting operation type 19 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #20 (type 20, layer 0)
>  Making operation type 20 opaque [module /[Default VFS]/]
>  Accepting operation type 20 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #21 (type 21, layer 0)
>  Making operation type 21 opaque [module /[Default VFS]/]
>  Accepting operation type 21 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #22 (type 22, layer 0)
>  Making operation type 22 opaque [module /[Default VFS]/]
>  Accepting operation type 22 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #23 (type 23, layer 0)
>  Making operation type 23 opaque [module /[Default VFS]/]
>  Accepting operation type 23 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #24 (type 24, layer 0)
>  Making operation type 24 opaque [module /[Default VFS]/]
>  Accepting operation type 24 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #25 (type 25, layer 0)
>  Making operation type 25 opaque [module /[Default VFS]/]
>  Accepting operation type 25 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #26 (type 26, layer 0)
>  Making operation type 26 opaque [module /[Default VFS]/]
>  Accepting operation type 26 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #27 (type 27, layer 0)
>  Making operation type 27 opaque [module /[Default VFS]/]
>  Accepting operation type 27 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #28 (type 28, layer 0)
>  Making operation type 28 opaque [module /[Default VFS]/]
>  Accepting operation type 28 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #29 (type 29, layer 0)
>  Making operation type 29 opaque [module /[Default VFS]/]
>  Accepting operation type 29 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #30 (type 30, layer 0)
>  Making operation type 30 opaque [module /[Default VFS]/]
>  Accepting operation type 30 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #31 (type 31, layer 0)
>  Making operation type 31 opaque [module /[Default VFS]/]
>  Accepting operation type 31 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #32 (type 32, layer 0)
>  Making operation type 32 opaque [module /[Default VFS]/]
>  Accepting operation type 32 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #33 (type 33, layer 0)
>  Making operation type 33 opaque [module /[Default VFS]/]
>  Accepting operation type 33 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #34 (type 34, layer 0)
>  Making operation type 34 opaque [module /[Default VFS]/]
>  Accepting operation type 34 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #35 (type 35, layer 0)
>  Making operation type 35 opaque [module /[Default VFS]/]
>  Accepting operation type 35 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #36 (type 36, layer 0)
>  Making operation type 36 opaque [module /[Default VFS]/]
>  Accepting operation type 36 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #37 (type 37, layer 0)
>  Making operation type 37 opaque [module /[Default VFS]/]
>  Accepting operation type 37 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #38 (type 38, layer 0)
>  Making operation type 38 opaque [module /[Default VFS]/]
>  Accepting operation type 38 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #39 (type 39, layer 0)
>  Making operation type 39 opaque [module /[Default VFS]/]
>  Accepting operation type 39 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #40 (type 40, layer 0)
>  Making operation type 40 opaque [module /[Default VFS]/]
>  Accepting operation type 40 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #41 (type 41, layer 0)
>  Making operation type 41 opaque [module /[Default VFS]/]
>  Accepting operation type 41 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #42 (type 42, layer 0)
>  Making operation type 42 opaque [module /[Default VFS]/]
>  Accepting operation type 42 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #43 (type 43, layer 0)
>  Making operation type 43 opaque [module /[Default VFS]/]
>  Accepting operation type 43 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #44 (type 44, layer 0)
>  Making operation type 44 opaque [module /[Default VFS]/]
>  Accepting operation type 44 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #45 (type 45, layer 0)
>  Making operation type 45 opaque [module /[Default VFS]/]
>  Accepting operation type 45 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #46 (type 46, layer 0)
>  Making operation type 46 opaque [module /[Default VFS]/]
>  Accepting operation type 46 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #47 (type 47, layer 0)
>  Making operation type 47 opaque [module /[Default VFS]/]
>  Accepting operation type 47 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #48 (type 48, layer 0)
>  Making operation type 48 opaque [module /[Default VFS]/]
>  Accepting operation type 48 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #49 (type 49, layer 0)
>  Making operation type 49 opaque [module /[Default VFS]/]
>  Accepting operation type 49 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #50 (type 50, layer 0)
>  Making operation type 50 opaque [module /[Default VFS]/]
>  Accepting operation type 50 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #51 (type 51, layer 0)
>  Making operation type 51 opaque [module /[Default VFS]/]
>  Accepting operation type 51 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #52 (type 52, layer 0)
>  Making operation type 52 opaque [module /[Default VFS]/]
>  Accepting operation type 52 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #53 (type 53, layer 0)
>  Making operation type 53 opaque [module /[Default VFS]/]
>  Accepting operation type 53 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #54 (type 54, layer 0)
>  Making operation type 54 opaque [module /[Default VFS]/]
>  Accepting operation type 54 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #55 (type 55, layer 0)
>  Making operation type 55 opaque [module /[Default VFS]/]
>  Accepting operation type 55 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #56 (type 56, layer 0)
>  Making operation type 56 opaque [module /[Default VFS]/]
>  Accepting operation type 56 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #57 (type 57, layer 0)
>  Making operation type 57 opaque [module /[Default VFS]/]
>  Accepting operation type 57 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #58 (type 58, layer 0)
>  Making operation type 58 opaque [module /[Default VFS]/]
>  Accepting operation type 58 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #59 (type 59, layer 0)
>  Making operation type 59 opaque [module /[Default VFS]/]
>  Accepting operation type 59 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #60 (type 60, layer 0)
>  Making operation type 60 opaque [module /[Default VFS]/]
>  Accepting operation type 60 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #61 (type 61, layer 0)
>  Making operation type 61 opaque [module /[Default VFS]/]
>  Accepting operation type 61 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #62 (type 62, layer 0)
>  Making operation type 62 opaque [module /[Default VFS]/]
>  Accepting operation type 62 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #63 (type 63, layer 0)
>  Making operation type 63 opaque [module /[Default VFS]/]
>  Accepting operation type 63 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #64 (type 64, layer 0)
>  Making operation type 64 opaque [module /[Default VFS]/]
>  Accepting operation type 64 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #65 (type 65, layer 0)
>  Making operation type 65 opaque [module /[Default VFS]/]
>  Accepting operation type 65 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #66 (type 66, layer 0)
>  Making operation type 66 opaque [module /[Default VFS]/]
>  Accepting operation type 66 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #67 (type 67, layer 0)
>  Making operation type 67 opaque [module /[Default VFS]/]
>  Accepting operation type 67 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #68 (type 68, layer 0)
>  Making operation type 68 opaque [module /[Default VFS]/]
>  Accepting operation type 68 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #69 (type 69, layer 0)
>  Making operation type 69 opaque [module /[Default VFS]/]
>  Accepting operation type 69 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #70 (type 70, layer 0)
>  Making operation type 70 opaque [module /[Default VFS]/]
>  Accepting operation type 70 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #71 (type 71, layer 0)
>  Making operation type 71 opaque [module /[Default VFS]/]
>  Accepting operation type 71 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #72 (type 72, layer 0)
>  Making operation type 72 opaque [module /[Default VFS]/]
>  Accepting operation type 72 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #73 (type 73, layer 0)
>  Making operation type 73 opaque [module /[Default VFS]/]
>  Accepting operation type 73 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #74 (type 74, layer 0)
>  Making operation type 74 opaque [module /[Default VFS]/]
>  Accepting operation type 74 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #75 (type 75, layer 0)
>  Making operation type 75 opaque [module /[Default VFS]/]
>  Accepting operation type 75 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #76 (type 76, layer 0)
>  Making operation type 76 opaque [module /[Default VFS]/]
>  Accepting operation type 76 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #77 (type 77, layer 0)
>  Making operation type 77 opaque [module /[Default VFS]/]
>  Accepting operation type 77 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #78 (type 78, layer 0)
>  Making operation type 78 opaque [module /[Default VFS]/]
>  Accepting operation type 78 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #79 (type 79, layer 0)
>  Making operation type 79 opaque [module /[Default VFS]/]
>  Accepting operation type 79 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #80 (type 80, layer 0)
>  Making operation type 80 opaque [module /[Default VFS]/]
>  Accepting operation type 80 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #81 (type 81, layer 0)
>  Making operation type 81 opaque [module /[Default VFS]/]
>  Accepting operation type 81 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #82 (type 82, layer 0)
>  Making operation type 82 opaque [module /[Default VFS]/]
>  Accepting operation type 82 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #83 (type 83, layer 0)
>  Making operation type 83 opaque [module /[Default VFS]/]
>  Accepting operation type 83 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #84 (type 84, layer 0)
>  Making operation type 84 opaque [module /[Default VFS]/]
>  Accepting operation type 84 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #85 (type 85, layer 0)
>  Making operation type 85 opaque [module /[Default VFS]/]
>  Accepting operation type 85 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #86 (type 86, layer 0)
>  Making operation type 86 opaque [module /[Default VFS]/]
>  Accepting operation type 86 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #87 (type 87, layer 0)
>  Making operation type 87 opaque [module /[Default VFS]/]
>  Accepting operation type 87 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #88 (type 88, layer 0)
>  Making operation type 88 opaque [module /[Default VFS]/]
>  Accepting operation type 88 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #89 (type 89, layer 0)
>  Making operation type 89 opaque [module /[Default VFS]/]
>  Accepting operation type 89 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #90 (type 90, layer 0)
>  Making operation type 90 opaque [module /[Default VFS]/]
>  Accepting operation type 90 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #91 (type 91, layer 0)
>  Making operation type 91 opaque [module /[Default VFS]/]
>  Accepting operation type 91 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #92 (type 92, layer 0)
>  Making operation type 92 opaque [module /[Default VFS]/]
>  Accepting operation type 92 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #93 (type 93, layer 0)
>  Making operation type 93 opaque [module /[Default VFS]/]
>  Accepting operation type 93 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #94 (type 94, layer 0)
>  Making operation type 94 opaque [module /[Default VFS]/]
>  Accepting operation type 94 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #95 (type 95, layer 0)
>  Making operation type 95 opaque [module /[Default VFS]/]
>  Accepting operation type 95 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #96 (type 96, layer 0)
>  Making operation type 96 opaque [module /[Default VFS]/]
>  Accepting operation type 96 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #97 (type 97, layer 0)
>  Making operation type 97 opaque [module /[Default VFS]/]
>  Accepting operation type 97 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #98 (type 98, layer 0)
>  Making operation type 98 opaque [module /[Default VFS]/]
>  Accepting operation type 98 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #99 (type 99, layer 0)
>  Making operation type 99 opaque [module /[Default VFS]/]
>  Accepting operation type 99 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #100 (type 100, layer 0)
>  Making operation type 100 opaque [module /[Default VFS]/]
>  Accepting operation type 100 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #101 (type 101, layer 0)
>  Making operation type 101 opaque [module /[Default VFS]/]
>  Accepting operation type 101 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #102 (type 102, layer 0)
>  Making operation type 102 opaque [module /[Default VFS]/]
>  Accepting operation type 102 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/connection.c:claim_connection(142)
>  claiming [root]
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
>  Locking key 0E3F000001000000726F
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
>  Allocated locked data 0x0x20c5a350
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
>  Unlocking key 0E3F000001000000726F
>[2009/10/30 19:31:29,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid root does not start with 'S-'.
>[2009/10/30 19:31:29, 10] passdb/lookup_sid.c:lookup_name(69)
>  lookup_name: OLDJUNK\root => OLDJUNK (domain), root (name)
>[2009/10/30 19:31:29, 10] passdb/lookup_sid.c:lookup_name(70)
>  lookup_name: flags = 0x073
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_username(572)
>  pdb_set_username: setting username root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_domain(595)
>  pdb_set_domain: setting domain OLDJUNK, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_nt_username(618)
>  pdb_set_nt_username: setting nt username , was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_fullname(641)
>  pdb_set_full_name: setting full name Charlie &, was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_homedir(734)
>  pdb_set_homedir: setting home dir \\oldjunk\root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(710)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_logon_script(664)
>  pdb_set_logon_script: setting logon script , was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_profile_path(687)
>  pdb_set_profile_path: setting profile path \\oldjunk\root\profile, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_workstations(777)
>  pdb_set_workstations: setting workstations , was 
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_user_sid(501)
>  pdb_set_user_sid: setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001
>[2009/10/30 19:31:29, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001 from rid 1001
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: maximum password age, val: -1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_alloc(133)
>  Finding user root
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_internals(77)
>  Trying _Get_Pwnam(), username as lowercase is root
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_internals(110)
>  Get_Pwnam_internals did find user [root]!
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_username(572)
>  pdb_set_username: setting username root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_domain(595)
>  pdb_set_domain: setting domain OLDJUNK, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_nt_username(618)
>  pdb_set_nt_username: setting nt username , was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_fullname(641)
>  pdb_set_full_name: setting full name Charlie &, was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_homedir(734)
>  pdb_set_homedir: setting home dir \\oldjunk\root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(710)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_logon_script(664)
>  pdb_set_logon_script: setting logon script , was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_profile_path(687)
>  pdb_set_profile_path: setting profile path \\oldjunk\root\profile, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_workstations(777)
>  pdb_set_workstations: setting workstations , was 
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_user_sid(501)
>  pdb_set_user_sid: setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001
>[2009/10/30 19:31:29, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001 from rid 1001
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] smbd/share_access.c:user_ok_token(234)
>  user_ok_token: share root is ok for unix user root
>[2009/10/30 19:31:29, 10] smbd/share_access.c:is_share_read_only_for_token(279)
>  is_share_read_only_for_user: share root is read-write for unix user root
>[2009/10/30 19:31:29, 10] lib/util_seaccess.c:se_map_generic(58)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2009/10/30 19:31:29, 10] lib/util_seaccess.c:se_map_generic(58)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: maximum password age, val: -1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_username(572)
>  pdb_set_username: setting username root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_domain(595)
>  pdb_set_domain: setting domain OLDJUNK, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_nt_username(618)
>  pdb_set_nt_username: setting nt username , was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_fullname(641)
>  pdb_set_full_name: setting full name Charlie &, was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_homedir(734)
>  pdb_set_homedir: setting home dir \\oldjunk\root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(710)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_logon_script(664)
>  pdb_set_logon_script: setting logon script , was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_profile_path(687)
>  pdb_set_profile_path: setting profile path \\oldjunk\root\profile, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_workstations(777)
>  pdb_set_workstations: setting workstations , was 
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_user_sid(501)
>  pdb_set_user_sid: setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001
>[2009/10/30 19:31:29, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001 from rid 1001
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(528)
>  NT user token of user S-1-5-21-3710583579-4186145173-3854701597-1001
>  contains 7 SIDs
>  SID[  0]: S-1-5-21-3710583579-4186145173-3854701597-1001
>  SID[  1]: S-1-22-2-0
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-11
>  SID[  5]: S-1-22-2-5
>  SID[  6]: S-1-22-1-0
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 2 supplementary groups
>  Group[  0]: 0
>  Group[  1]: 5
>[2009/10/30 19:31:29,  5] smbd/uid.c:change_to_user(355)
>  change_to_user uid=(0,0) gid=(0,0)
>[2009/10/30 19:31:29,  1] smbd/service.c:make_connection_snum(1119)
>  dadsdesktop (10.0.2.149) connect to service root initially as user root (uid=0, gid=0) (pid 16142)
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  5] smbd/uid.c:change_to_root_user(370)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/10/30 19:31:29,  3] smbd/reply.c:reply_tcon_and_X(794)
>  tconX service=ROOT 
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=62
>  smb_com=0x75
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=65279
>  smb_uid=100
>  smb_mid=57536
>  smt_wct=7
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    1 (0x1)
>  smb_vwv[ 3]=  511 (0x1FF)
>  smb_vwv[ 4]=   31 (0x1F)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_bcc=13
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 41 3A 00 4E 00 54 00 46  00 53 00 00 00           A:.N.T.F .S...
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 98
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x62
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 4 of length 102 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=98
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=57600
>  smt_wct=15
>  smb_vwv[ 0]=   30 (0x1E)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=   40 (0x28)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=    0 (0x0)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=   30 (0x1E)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    5 (0x5)
>  smb_bcc=33
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 00 00 EC 03 00 00 00  00 5C 00 6D 00 69 00 73  ........ .\.m.i.s
>  [010] 00 63 00 2D 00 66 00 69  00 6C 00 65 00 73 00 00  .c.-.f.i .l.e.s..
>  [020] 00                                                . 
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans2 (pid 16142) conn 0x20c5c030
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(528)
>  NT user token of user S-1-5-21-3710583579-4186145173-3854701597-1001
>  contains 7 SIDs
>  SID[  0]: S-1-5-21-3710583579-4186145173-3854701597-1001
>  SID[  1]: S-1-22-2-0
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-11
>  SID[  5]: S-1-22-2-5
>  SID[  6]: S-1-22-1-0
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 2 supplementary groups
>  Group[  0]: 0
>  Group[  1]: 5
>[2009/10/30 19:31:29,  5] smbd/uid.c:change_to_user(355)
>  change_to_user uid=(0,0) gid=(0,0)
>[2009/10/30 19:31:29,  4] smbd/vfs.c:vfs_ChDir(733)
>  vfs_ChDir to /usr/home/root/Documents
>[2009/10/30 19:31:29,  3] smbd/trans2.c:call_trans2qfilepathinfo(3943)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
>[2009/10/30 19:31:29,  5] smbd/filename.c:unix_convert(155)
>  unix_convert called on file "misc-files"
>[2009/10/30 19:31:29, 10] smbd/statcache.c:stat_cache_lookup(235)
>  stat_cache_lookup: lookup failed for name [MISC-FILES]
>[2009/10/30 19:31:29,  5] smbd/statcache.c:stat_cache_add(138)
>  stat_cache_add: Added entry (20c59250:size a) MISC-FILES -> misc-files
>[2009/10/30 19:31:29,  5] smbd/filename.c:unix_convert(304)
>  conversion finished misc-files -> misc-files
>[2009/10/30 19:31:29, 10] locking/locking.c:fetch_share_mode_unlocked(858)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2009/10/30 19:31:29,  3] smbd/trans2.c:call_trans2qfilepathinfo(4057)
>  call_trans2qfilepathinfo misc-files (fnum = -1) level=1004 call=5 total_data=0
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(361)
>  dos_mode: misc-files
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
>  dos_mode_from_sbuf returning d
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(403)
>  dos_mode returning d
>[2009/10/30 19:31:29, 10] smbd/trans2.c:call_trans2qfilepathinfo(4299)
>  call_trans2qfilepathinfo: SMB_FILE_BASIC_INFORMATION
>[2009/10/30 19:31:29,  5] smbd/trans2.c:call_trans2qfilepathinfo(4309)
>  SMB_QFBI - create: Sun Oct 25 15:15:50 2009
>   access: Fri Oct 30 17:02:23 2009
>   write: Thu Oct 29 22:09:03 2009
>   change: Thu Oct 29 22:09:03 2009
>   mode: 10
>[2009/10/30 19:31:29,  9] smbd/trans2.c:send_trans2_replies(840)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 40, useable_space = 131010
>[2009/10/30 19:31:29,  9] smbd/trans2.c:send_trans2_replies(842)
>  t2_rep: params_to_send = 2, data_to_send = 40, paramsize = 2, datasize = 40
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=100
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=57600
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   40 (0x28)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   40 (0x28)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=45
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 00 00 00 00 00 9F ED  F1 AF 55 CA 01 80 C1 84  ........ ..U.....
>  [010] A8 AC 59 CA 01 80 31 5C  55 0E 59 CA 01 80 31 5C  ..Y...1\ U.Y...1\
>  [020] 55 0E 59 CA 01 10 00 00  00 00 00 00 00           U.Y..... .....
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 98
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x62
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 5 of length 102 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=98
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=57664
>  smt_wct=15
>  smb_vwv[ 0]=   30 (0x1E)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=   24 (0x18)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=    0 (0x0)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=   30 (0x1E)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    5 (0x5)
>  smb_bcc=33
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 00 00 ED 03 00 00 00  00 5C 00 6D 00 69 00 73  ........ .\.m.i.s
>  [010] 00 63 00 2D 00 66 00 69  00 6C 00 65 00 73 00 00  .c.-.f.i .l.e.s..
>  [020] 00                                                . 
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans2 (pid 16142) conn 0x20c5c030
>[2009/10/30 19:31:29,  4] smbd/uid.c:change_to_user(258)
>  change_to_user: Skipping user change - already user
>[2009/10/30 19:31:29,  3] smbd/trans2.c:call_trans2qfilepathinfo(3943)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1005
>[2009/10/30 19:31:29,  5] smbd/filename.c:unix_convert(155)
>  unix_convert called on file "misc-files"
>[2009/10/30 19:31:29, 10] smbd/statcache.c:stat_cache_lookup(274)
>  stat_cache_lookup: lookup succeeded for name [MISC-FILES] -> [misc-files]
>[2009/10/30 19:31:29, 10] locking/locking.c:fetch_share_mode_unlocked(858)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2009/10/30 19:31:29,  3] smbd/trans2.c:call_trans2qfilepathinfo(4057)
>  call_trans2qfilepathinfo misc-files (fnum = -1) level=1005 call=5 total_data=0
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(361)
>  dos_mode: misc-files
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
>  dos_mode_from_sbuf returning d
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(403)
>  dos_mode returning d
>[2009/10/30 19:31:29, 10] smbd/trans2.c:call_trans2qfilepathinfo(4320)
>  call_trans2qfilepathinfo: SMB_FILE_STANDARD_INFORMATION
>[2009/10/30 19:31:29,  9] smbd/trans2.c:send_trans2_replies(840)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 24, useable_space = 131010
>[2009/10/30 19:31:29,  9] smbd/trans2.c:send_trans2_replies(842)
>  t2_rep: params_to_send = 2, data_to_send = 24, paramsize = 2, datasize = 24
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=84
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=57664
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   24 (0x18)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   24 (0x18)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=29
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
>  [010] 00 00 00 00 00 01 00 00  00 00 01 00 00           ........ .....
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 98
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x62
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 6 of length 102 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=98
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=57728
>  smt_wct=15
>  smb_vwv[ 0]=   30 (0x1E)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=   24 (0x18)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=    0 (0x0)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=   30 (0x1E)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    5 (0x5)
>  smb_bcc=33
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 00 00 ED 03 00 00 00  00 5C 00 6D 00 69 00 73  ........ .\.m.i.s
>  [010] 00 63 00 2D 00 66 00 69  00 6C 00 65 00 73 00 00  .c.-.f.i .l.e.s..
>  [020] 00                                                . 
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans2 (pid 16142) conn 0x20c5c030
>[2009/10/30 19:31:29,  4] smbd/uid.c:change_to_user(258)
>  change_to_user: Skipping user change - already user
>[2009/10/30 19:31:29,  3] smbd/trans2.c:call_trans2qfilepathinfo(3943)
>  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1005
>[2009/10/30 19:31:29,  5] smbd/filename.c:unix_convert(155)
>  unix_convert called on file "misc-files"
>[2009/10/30 19:31:29, 10] smbd/statcache.c:stat_cache_lookup(274)
>  stat_cache_lookup: lookup succeeded for name [MISC-FILES] -> [misc-files]
>[2009/10/30 19:31:29, 10] locking/locking.c:fetch_share_mode_unlocked(858)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2009/10/30 19:31:29,  3] smbd/trans2.c:call_trans2qfilepathinfo(4057)
>  call_trans2qfilepathinfo misc-files (fnum = -1) level=1005 call=5 total_data=0
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(361)
>  dos_mode: misc-files
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
>  dos_mode_from_sbuf returning d
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(403)
>  dos_mode returning d
>[2009/10/30 19:31:29, 10] smbd/trans2.c:call_trans2qfilepathinfo(4320)
>  call_trans2qfilepathinfo: SMB_FILE_STANDARD_INFORMATION
>[2009/10/30 19:31:29,  9] smbd/trans2.c:send_trans2_replies(840)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 24, useable_space = 131010
>[2009/10/30 19:31:29,  9] smbd/trans2.c:send_trans2_replies(842)
>  t2_rep: params_to_send = 2, data_to_send = 24, paramsize = 2, datasize = 24
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=84
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=57728
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=   24 (0x18)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=   24 (0x18)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=29
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
>  [010] 00 00 00 00 00 01 00 00  00 00 01 00 00           ........ .....
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 122
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x7a
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 7 of length 126 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=122
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=57792
>  smt_wct=15
>  smb_vwv[ 0]=   54 (0x36)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=   10 (0xA)
>  smb_vwv[ 3]=16384 (0x4000)
>  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]=   54 (0x36)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    1 (0x1)
>  smb_bcc=57
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 00 00 16 00 56 05 07  00 04 01 00 00 00 00 5C  .....V.. .......\
>  [010] 00 6D 00 69 00 73 00 63  00 2D 00 66 00 69 00 6C  .m.i.s.c .-.f.i.l
>  [020] 00 65 00 73 00 5C 00 50  00 69 00 63 00 74 00 75  .e.s.\.P .i.c.t.u
>  [030] 00 72 00 65 00 73 00 00  00                       .r.e.s.. .
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans2 (pid 16142) conn 0x20c5c030
>[2009/10/30 19:31:29,  4] smbd/uid.c:change_to_user(258)
>  change_to_user: Skipping user change - already user
>[2009/10/30 19:31:29,  3] smbd/trans2.c:call_trans2findfirst(1926)
>  call_trans2findfirst: dirtype = 16, maxentries = 1366, close_after_first=1, close_if_end = 1 requires_resume_key = 1 level = 0x104, max_data_bytes = 16384
>[2009/10/30 19:31:29,  5] smbd/filename.c:unix_convert(155)
>  unix_convert called on file "misc-files/Pictures"
>[2009/10/30 19:31:29, 10] smbd/statcache.c:stat_cache_lookup(235)
>  stat_cache_lookup: lookup failed for name [MISC-FILES/PICTURES]
>[2009/10/30 19:31:29, 10] smbd/statcache.c:stat_cache_lookup(274)
>  stat_cache_lookup: lookup succeeded for name [MISC-FILES] -> [misc-files]
>[2009/10/30 19:31:29,  5] smbd/statcache.c:stat_cache_add(138)
>  stat_cache_add: Added entry (20c59220:size 13) MISC-FILES/PICTURES -> misc-files/Pictures
>[2009/10/30 19:31:29,  5] smbd/filename.c:unix_convert(304)
>  conversion finished misc-files/Pictures -> misc-files/Pictures
>[2009/10/30 19:31:29,  5] smbd/trans2.c:call_trans2findfirst(2013)
>  dir=misc-files, mask = Pictures
>[2009/10/30 19:31:29,  5] smbd/dir.c:dptr_create(400)
>  dptr_create dir=misc-files
>[2009/10/30 19:31:29,  3] smbd/dir.c:dptr_create(518)
>  creating new dirptr 256 for path misc-files, expect_close = 1
>[2009/10/30 19:31:29,  4] smbd/trans2.c:call_trans2findfirst(2080)
>  dptr_num is 256, wcard = Pictures, attr = 22
>[2009/10/30 19:31:29,  8] smbd/trans2.c:call_trans2findfirst(2085)
>  dirpath=<misc-files> dontdescend=<>
>[2009/10/30 19:31:29,  8] smbd/trans2.c:get_lanman2_dir_entry(1317)
>  get_lanman2_dir_entry:readdir on dirptr 0x20c061f0 now at offset -1
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(361)
>  dos_mode: misc-files/Pictures
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
>  dos_mode_from_sbuf returning d
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(403)
>  dos_mode returning d
>[2009/10/30 19:31:29, 10] locking/locking.c:fetch_share_mode_unlocked(858)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2009/10/30 19:31:29,  5] smbd/trans2.c:get_lanman2_dir_entry(1448)
>  get_lanman2_dir_entry: found misc-files/Pictures fname=Pictures
>[2009/10/30 19:31:29, 10] smbd/trans2.c:get_lanman2_dir_entry(1597)
>  get_lanman2_dir_entry: SMB_FIND_FILE_BOTH_DIRECTORY_INFO
>[2009/10/30 19:31:29,  5] smbd/trans2.c:call_trans2findfirst(2141)
>  call_trans2findfirst - (2) closing dptr_num 256
>[2009/10/30 19:31:29,  4] smbd/dir.c:dptr_close_internal(249)
>  closing dptr key 256
>[2009/10/30 19:31:29,  9] smbd/trans2.c:send_trans2_replies(840)
>  t2_rep: params_sent_thistime = 10, data_sent_thistime = 112, useable_space = 131010
>[2009/10/30 19:31:29,  9] smbd/trans2.c:send_trans2_replies(842)
>  t2_rep: params_to_send = 10, data_to_send = 112, paramsize = 10, datasize = 112
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=180
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=57792
>  smt_wct=10
>  smb_vwv[ 0]=   10 (0xA)
>  smb_vwv[ 1]=  112 (0x70)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=   10 (0xA)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=  112 (0x70)
>  smb_vwv[ 7]=   68 (0x44)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=125
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 FD FF 01 00 01 00 00  00 00 00 00 00 70 00 00  ........ .....p..
>  [010] 00 00 00 00 00 00 33 60  FE 2E CB C7 01 80 C1 84  ......3` ........
>  [020] A8 AC 59 CA 01 00 33 60  FE 2E CB C7 01 00 33 60  ..Y...3` ......3`
>  [030] FE 2E CB C7 01 00 00 00  00 00 00 00 00 00 00 00  ........ ........
>  [040] 00 00 00 00 00 10 00 00  00 10 00 00 00 00 00 00  ........ ........
>  [050] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
>  [060] 00 00 00 00 00 00 00 00  00 00 00 50 00 69 00 63  ........ ...P.i.c
>  [070] 00 74 00 75 00 72 00 65  00 73 00 00 00           .t.u.r.e .s...
>[2009/10/30 19:31:29,  4] smbd/trans2.c:call_trans2findfirst(2185)
>  SMBtrans2 mask=Pictures directory=misc-files dirtype=22 numentries=1
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 88
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x58
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 8 of length 92 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=88
>  smb_com=0xa2
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=57856
>  smt_wct=24
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=57054 (0xDEDE)
>  smb_vwv[ 2]=  512 (0x200)
>  smb_vwv[ 3]= 4096 (0x1000)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=  256 (0x100)
>  smb_vwv[ 8]= 4096 (0x1000)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_vwv[10]=    0 (0x0)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=32768 (0x8000)
>  smb_vwv[14]=    0 (0x0)
>  smb_vwv[15]= 1792 (0x700)
>  smb_vwv[16]=    0 (0x0)
>  smb_vwv[17]=  256 (0x100)
>  smb_vwv[18]=    0 (0x0)
>  smb_vwv[19]=  256 (0x100)
>  smb_vwv[20]=   64 (0x40)
>  smb_vwv[21]=  512 (0x200)
>  smb_vwv[22]=    0 (0x0)
>  smb_vwv[23]=    0 (0x0)
>  smb_bcc=5
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 03 5C 00 00 00                                    .\... 
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBntcreateX (pid 16142) conn 0x20c5c030
>[2009/10/30 19:31:29,  4] smbd/uid.c:change_to_user(258)
>  change_to_user: Skipping user change - already user
>[2009/10/30 19:31:29, 10] smbd/nttrans.c:reply_ntcreate_and_X(454)
>  reply_ntcreate_and_X: flags = 0x10, access_mask = 0x100001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x4001 root_dir_fid = 0x0, fname = 
>[2009/10/30 19:31:29, 10] smbd/open.c:create_file(3283)
>  create_file: access_mask = 0x100001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x4001 oplock_request = 0x0 root_dir_fid = 0x0, ea_list = 0x0x0, sd = 0x0x0, fname = 
>[2009/10/30 19:31:29,  5] smbd/filename.c:unix_convert(155)
>  unix_convert called on file ""
>[2009/10/30 19:31:29,  5] smbd/filename.c:unix_convert(188)
>  conversion finished "" -> .
>[2009/10/30 19:31:29, 10] smbd/open.c:create_file_unixpath(2889)
>  create_file_unixpath: access_mask = 0x100001 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x4001 oplock_request = 0x0 ea_list = 0x0x0, sd = 0x0x0, fname = .
>[2009/10/30 19:31:29,  5] smbd/open.c:open_directory(2396)
>  open_directory: opening directory ., access_mask = 0x100001, share_access = 0x7 create_options = 0x4001, create_disposition = 0x1, file_attributes = 0x80
>[2009/10/30 19:31:29,  5] smbd/files.c:file_new(121)
>  allocated file structure 2671, fnum = 6767 (1 used)
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
>  Locking key 5C000000000000000154
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
>  Allocated locked data 0x0x20c59240
>[2009/10/30 19:31:29, 10] locking/locking.c:unparse_share_modes(683)
>  unparse_share_modes: del: 0, owrt: Fri Oct 30 19:08:07 2009 CDT cwrt: Wed Dec 31 18:00:00 1969 CST, tok: 0, num: 1
>[2009/10/30 19:31:29, 10] locking/locking.c:print_share_mode_table(485)
>  print_share_mode_table: share_mode_entry[0]:  pid = 16142, share_access = 0x7, private_options = 0x4001, access_mask = 0x100081, mid = 0x0, type= 0x0, gen_id = 1, uid = 0, flags = 0, file_id 5c:9c5401
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
>  Unlocking key 5C000000000000000154
>[2009/10/30 19:31:29, 10] smbd/open.c:create_file_unixpath(3209)
>  create_file_unixpath: info=1
>[2009/10/30 19:31:29, 10] smbd/open.c:create_file(3477)
>  create_file: info=1
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(361)
>  dos_mode: .
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
>  dos_mode_from_sbuf returning d
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(403)
>  dos_mode returning d
>[2009/10/30 19:31:29,  5] smbd/nttrans.c:reply_ntcreate_and_X(607)
>  reply_ntcreate_and_X: fnum = 6767, open name = .
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=135
>  smb_com=0xa2
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=57856
>  smt_wct=42
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=28416 (0x6F00)
>  smb_vwv[ 3]=  282 (0x11A)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=32768 (0x8000)
>  smb_vwv[ 6]=21636 (0x5484)
>  smb_vwv[ 7]=64700 (0xFCBC)
>  smb_vwv[ 8]=51795 (0xCA53)
>  smb_vwv[ 9]=    1 (0x1)
>  smb_vwv[10]=24486 (0x5FA6)
>  smb_vwv[11]=49167 (0xC00F)
>  smb_vwv[12]=51801 (0xCA59)
>  smb_vwv[13]=32769 (0x8001)
>  smb_vwv[14]= 6093 (0x17CD)
>  smb_vwv[15]=48697 (0xBE39)
>  smb_vwv[16]=51801 (0xCA59)
>  smb_vwv[17]=32769 (0x8001)
>  smb_vwv[18]= 6093 (0x17CD)
>  smb_vwv[19]=48697 (0xBE39)
>  smb_vwv[20]=51801 (0xCA59)
>  smb_vwv[21]= 4097 (0x1001)
>  smb_vwv[22]=    0 (0x0)
>  smb_vwv[23]=    0 (0x0)
>  smb_vwv[24]=    0 (0x0)
>  smb_vwv[25]=    0 (0x0)
>  smb_vwv[26]=    0 (0x0)
>  smb_vwv[27]=    0 (0x0)
>  smb_vwv[28]=    0 (0x0)
>  smb_vwv[29]=    0 (0x0)
>  smb_vwv[30]=    0 (0x0)
>  smb_vwv[31]=    0 (0x0)
>  smb_vwv[32]= 1792 (0x700)
>  smb_vwv[33]=  256 (0x100)
>  smb_vwv[34]=    0 (0x0)
>  smb_vwv[35]=    0 (0x0)
>  smb_vwv[36]=    0 (0x0)
>  smb_vwv[37]=    0 (0x0)
>  smb_vwv[38]=    0 (0x0)
>  smb_vwv[39]=    0 (0x0)
>  smb_vwv[40]=    0 (0x0)
>  smb_vwv[41]=    0 (0x0)
>  smb_bcc=0
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 72
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x48
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 9 of length 76 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=72
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=57920
>  smt_wct=15
>  smb_vwv[ 0]=    4 (0x4)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    2 (0x2)
>  smb_vwv[ 3]=    8 (0x8)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=    0 (0x0)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    4 (0x4)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    7 (0x7)
>  smb_bcc=7
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 00 00 6F 1A EE 03                              ...o... 
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans2 (pid 16142) conn 0x20c5c030
>[2009/10/30 19:31:29,  4] smbd/uid.c:change_to_user(258)
>  change_to_user: Skipping user change - already user
>[2009/10/30 19:31:29,  3] smbd/trans2.c:call_trans2qfilepathinfo(3867)
>  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1006
>[2009/10/30 19:31:29, 10] locking/locking.c:parse_share_modes(519)
>  parse_share_modes: delete_on_close: 0, owrt: Fri Oct 30 19:08:07 2009 CDT, cwrt: Wed Dec 31 18:00:00 1969 CST, tok: 0, num_share_modes: 1
>[2009/10/30 19:31:29, 10] locking/locking.c:parse_share_modes(616)
>  parse_share_modes: share_mode_entry[0]:  pid = 16142, share_access = 0x7, private_options = 0x4001, access_mask = 0x100081, mid = 0x0, type= 0x0, gen_id = 1, uid = 0, flags = 0, file_id 5c:9c5401
>[2009/10/30 19:31:29,  3] smbd/trans2.c:call_trans2qfilepathinfo(4057)
>  call_trans2qfilepathinfo . (fnum = 6767) level=1006 call=7 total_data=0
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(361)
>  dos_mode: .
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
>  dos_mode_from_sbuf returning d
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(403)
>  dos_mode returning d
>[2009/10/30 19:31:29, 10] smbd/trans2.c:call_trans2qfilepathinfo(4425)
>  call_trans2qfilepathinfo: SMB_FILE_INTERNAL_INFORMATION
>[2009/10/30 19:31:29,  9] smbd/trans2.c:send_trans2_replies(840)
>  t2_rep: params_sent_thistime = 2, data_sent_thistime = 8, useable_space = 131010
>[2009/10/30 19:31:29,  9] smbd/trans2.c:send_trans2_replies(842)
>  t2_rep: params_to_send = 2, data_to_send = 8, paramsize = 2, datasize = 8
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=68
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=57920
>  smt_wct=10
>  smb_vwv[ 0]=    2 (0x2)
>  smb_vwv[ 1]=    8 (0x8)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=    2 (0x2)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    8 (0x8)
>  smb_vwv[ 7]=   60 (0x3C)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=13
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 00 00 00 00 01 54 9C  00 5C 00 00 00           ......T. .\...
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 41
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x29
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 10 of length 45 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=41
>  smb_com=0x4
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=65279
>  smb_uid=100
>  smb_mid=57984
>  smt_wct=3
>  smb_vwv[ 0]= 6767 (0x1A6F)
>  smb_vwv[ 1]=65535 (0xFFFF)
>  smb_vwv[ 2]=65535 (0xFFFF)
>  smb_bcc=0
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBclose (pid 16142) conn 0x20c5c030
>[2009/10/30 19:31:29,  4] smbd/uid.c:change_to_user(258)
>  change_to_user: Skipping user change - already user
>[2009/10/30 19:31:29,  3] smbd/reply.c:reply_close(4343)
>  close directory fnum=6767
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
>  Locking key 5C000000000000000154
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
>  Allocated locked data 0x0x20c59190
>[2009/10/30 19:31:29, 10] locking/locking.c:parse_share_modes(519)
>  parse_share_modes: delete_on_close: 0, owrt: Fri Oct 30 19:08:07 2009 CDT, cwrt: Wed Dec 31 18:00:00 1969 CST, tok: 0, num_share_modes: 1
>[2009/10/30 19:31:29, 10] locking/locking.c:parse_share_modes(616)
>  parse_share_modes: share_mode_entry[0]:  pid = 16142, share_access = 0x7, private_options = 0x4001, access_mask = 0x100081, mid = 0x0, type= 0x0, gen_id = 1, uid = 0, flags = 0, file_id 5c:9c5401
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
>  Unlocking key 5C000000000000000154
>[2009/10/30 19:31:29,  5] smbd/files.c:file_free(471)
>  freed files structure 6767 (0 used)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=35
>  smb_com=0x4
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=65279
>  smb_uid=100
>  smb_mid=57984
>  smt_wct=0
>  smb_bcc=0
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 104
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x68
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 11 of length 108 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=104
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=58048
>  smt_wct=15
>  smb_vwv[ 0]=   36 (0x24)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=   10 (0xA)
>  smb_vwv[ 3]=16384 (0x4000)
>  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]=   36 (0x24)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=    1 (0x1)
>  smb_bcc=39
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 00 00 16 00 56 05 07  00 04 01 00 00 00 00 5C  .....V.. .......\
>  [010] 00 6D 00 69 00 73 00 63  00 2D 00 66 00 69 00 6C  .m.i.s.c .-.f.i.l
>  [020] 00 65 00 73 00 00 00                              .e.s... 
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans2 (pid 16142) conn 0x20c5c030
>[2009/10/30 19:31:29,  4] smbd/uid.c:change_to_user(258)
>  change_to_user: Skipping user change - already user
>[2009/10/30 19:31:29,  3] smbd/trans2.c:call_trans2findfirst(1926)
>  call_trans2findfirst: dirtype = 16, maxentries = 1366, close_after_first=1, close_if_end = 1 requires_resume_key = 1 level = 0x104, max_data_bytes = 16384
>[2009/10/30 19:31:29,  5] smbd/filename.c:unix_convert(155)
>  unix_convert called on file "misc-files"
>[2009/10/30 19:31:29, 10] smbd/statcache.c:stat_cache_lookup(274)
>  stat_cache_lookup: lookup succeeded for name [MISC-FILES] -> [misc-files]
>[2009/10/30 19:31:29,  5] smbd/trans2.c:call_trans2findfirst(2013)
>  dir=./, mask = misc-files
>[2009/10/30 19:31:29,  5] smbd/dir.c:dptr_create(400)
>  dptr_create dir=./
>[2009/10/30 19:31:29,  3] smbd/dir.c:dptr_create(518)
>  creating new dirptr 256 for path ./, expect_close = 1
>[2009/10/30 19:31:29,  4] smbd/trans2.c:call_trans2findfirst(2080)
>  dptr_num is 256, wcard = misc-files, attr = 22
>[2009/10/30 19:31:29,  8] smbd/trans2.c:call_trans2findfirst(2085)
>  dirpath=<./> dontdescend=<>
>[2009/10/30 19:31:29,  8] smbd/trans2.c:get_lanman2_dir_entry(1317)
>  get_lanman2_dir_entry:readdir on dirptr 0x20c061f0 now at offset -1
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(361)
>  dos_mode: ./misc-files
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode_from_sbuf(170)
>  dos_mode_from_sbuf returning d
>[2009/10/30 19:31:29,  8] smbd/dosmode.c:dos_mode(403)
>  dos_mode returning d
>[2009/10/30 19:31:29, 10] locking/locking.c:fetch_share_mode_unlocked(858)
>  fetch_share_mode_unlocked: no share_mode record around (file not open)
>[2009/10/30 19:31:29,  5] smbd/trans2.c:get_lanman2_dir_entry(1448)
>  get_lanman2_dir_entry: found ./misc-files fname=misc-files
>[2009/10/30 19:31:29, 10] smbd/trans2.c:get_lanman2_dir_entry(1597)
>  get_lanman2_dir_entry: SMB_FIND_FILE_BOTH_DIRECTORY_INFO
>[2009/10/30 19:31:29, 10] smbd/mangle_hash2.c:hash2_name_to_8_3(609)
>  hash2_name_to_8_3: misc-files -> 6C599207 -> MU29YP~Z (cache=1)
>[2009/10/30 19:31:29,  5] smbd/trans2.c:call_trans2findfirst(2141)
>  call_trans2findfirst - (2) closing dptr_num 256
>[2009/10/30 19:31:29,  4] smbd/dir.c:dptr_close_internal(249)
>  closing dptr key 256
>[2009/10/30 19:31:29,  9] smbd/trans2.c:send_trans2_replies(840)
>  t2_rep: params_sent_thistime = 10, data_sent_thistime = 116, useable_space = 131010
>[2009/10/30 19:31:29,  9] smbd/trans2.c:send_trans2_replies(842)
>  t2_rep: params_to_send = 10, data_to_send = 116, paramsize = 10, datasize = 116
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=184
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=5288
>  smb_uid=100
>  smb_mid=58048
>  smt_wct=10
>  smb_vwv[ 0]=   10 (0xA)
>  smb_vwv[ 1]=  116 (0x74)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=   10 (0xA)
>  smb_vwv[ 4]=   56 (0x38)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=  116 (0x74)
>  smb_vwv[ 7]=   68 (0x44)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_bcc=129
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 FD FF 01 00 01 00 00  00 00 00 00 00 74 00 00  ........ .....t..
>  [010] 00 00 00 00 00 00 9F ED  F1 AF 55 CA 01 80 C1 84  ........ ..U.....
>  [020] A8 AC 59 CA 01 80 31 5C  55 0E 59 CA 01 80 31 5C  ..Y...1\ U.Y...1\
>  [030] 55 0E 59 CA 01 00 00 00  00 00 00 00 00 00 00 00  U.Y..... ........
>  [040] 00 00 00 00 00 10 00 00  00 14 00 00 00 00 00 00  ........ ........
>  [050] 00 10 00 4D 00 55 00 32  00 39 00 59 00 50 00 7E  ...M.U.2 .9.Y.P.~
>  [060] 00 5A 00 00 00 00 00 00  00 00 00 6D 00 69 00 73  .Z...... ...m.i.s
>  [070] 00 63 00 2D 00 66 00 69  00 6C 00 65 00 73 00 00  .c.-.f.i .l.e.s..
>  [080] 00                                                . 
>[2009/10/30 19:31:29,  4] smbd/trans2.c:call_trans2findfirst(2185)
>  SMBtrans2 mask=misc-files directory=./ dirtype=22 numentries=1
>[2009/10/30 19:31:29, 10] smbd/mangle_hash2.c:hash2_name_to_8_3(609)
>  hash2_name_to_8_3: misc-files -> 6C599207 -> MU29YP~Z (cache=1)
>[2009/10/30 19:31:29, 10] lib/util_sock.c:read_smb_length_return_keepalive(1187)
>  got smb length of 80
>[2009/10/30 19:31:29,  6] smbd/process.c:process_smb(1573)
>  got message type 0x0 of len 0x50
>[2009/10/30 19:31:29,  3] smbd/process.c:process_smb(1576)
>  Transaction 12 of length 84 (0 toread)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=80
>  smb_com=0x75
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=100
>  smb_mid=58112
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=   80 (0x50)
>  smb_vwv[ 2]=    8 (0x8)
>  smb_vwv[ 3]=    1 (0x1)
>  smb_bcc=37
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 00 5C 00 5C 00 4F 00 4C  00 44 00 4A 00 55 00 4E  .\.\.O.L .D.J.U.N
>  [010] 00 4B 00 5C 00 49 00 50  00 43 00 24 00 00 00 3F  .K.\.I.P .C.$...?
>  [020] 3F 3F 3F 3F 00                                    ????. 
>[2009/10/30 19:31:29,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtconX (pid 16142) conn 0x0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  5] smbd/uid.c:change_to_root_user(370)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/10/30 19:31:29,  4] smbd/reply.c:reply_tcon_and_X(720)
>  Client requested device type [?????] for share [IPC$]
>[2009/10/30 19:31:29,  5] smbd/service.c:make_connection(1306)
>  making a connection to 'normal' service ipc$
>[2009/10/30 19:31:29,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (10.0.2.)
>[2009/10/30 19:31:29,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2009/10/30 19:31:29,  2] lib/access.c:check_access(406)
>  Allowed connection from 10.0.2.149 (10.0.2.149)
>[2009/10/30 19:31:29, 10] smbd/share_access.c:user_ok_token(234)
>  user_ok_token: share IPC$ is ok for unix user root
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: maximum password age, val: -1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_username(572)
>  pdb_set_username: setting username root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_domain(595)
>  pdb_set_domain: setting domain OLDJUNK, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_nt_username(618)
>  pdb_set_nt_username: setting nt username , was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_fullname(641)
>  pdb_set_full_name: setting full name Charlie &, was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_homedir(734)
>  pdb_set_homedir: setting home dir \\oldjunk\root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(710)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_logon_script(664)
>  pdb_set_logon_script: setting logon script , was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_profile_path(687)
>  pdb_set_profile_path: setting profile path \\oldjunk\root\profile, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_workstations(777)
>  pdb_set_workstations: setting workstations , was 
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_user_sid(501)
>  pdb_set_user_sid: setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001
>[2009/10/30 19:31:29, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001 from rid 1001
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_alloc(133)
>  Finding user root
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_internals(77)
>  Trying _Get_Pwnam(), username as lowercase is root
>[2009/10/30 19:31:29,  5] lib/username.c:Get_Pwnam_internals(110)
>  Get_Pwnam_internals did find user [root]!
>[2009/10/30 19:31:29, 10] smbd/service.c:set_conn_connectpath(161)
>  set_conn_connectpath: service IPC$, connectpath = /tmp
>[2009/10/30 19:31:29,  3] smbd/service.c:make_connection_snum(879)
>  Connect path is '/tmp' for service [IPC$]
>[2009/10/30 19:31:29, 10] lib/util_seaccess.c:se_map_generic(58)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2009/10/30 19:31:29,  3] smbd/vfs.c:vfs_init_default(96)
>  Initialising default vfs hooks
>[2009/10/30 19:31:29,  3] smbd/vfs.c:vfs_init_custom(130)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2009/10/30 19:31:29, 10] smbd/vfs.c:vfs_find_backend_entry(48)
>  vfs_find_backend_entry called for /[Default VFS]/
>  Successfully loaded vfs module [/[Default VFS]/] with the new modules system
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #0 (type 0, layer 0)
>  Making operation type 0 opaque [module /[Default VFS]/]
>  Accepting operation type 0 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #1 (type 1, layer 0)
>  Making operation type 1 opaque [module /[Default VFS]/]
>  Accepting operation type 1 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #2 (type 2, layer 0)
>  Making operation type 2 opaque [module /[Default VFS]/]
>  Accepting operation type 2 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #3 (type 3, layer 0)
>  Making operation type 3 opaque [module /[Default VFS]/]
>  Accepting operation type 3 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #4 (type 4, layer 0)
>  Making operation type 4 opaque [module /[Default VFS]/]
>  Accepting operation type 4 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #5 (type 5, layer 0)
>  Making operation type 5 opaque [module /[Default VFS]/]
>  Accepting operation type 5 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #6 (type 6, layer 0)
>  Making operation type 6 opaque [module /[Default VFS]/]
>  Accepting operation type 6 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #7 (type 7, layer 0)
>  Making operation type 7 opaque [module /[Default VFS]/]
>  Accepting operation type 7 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #8 (type 8, layer 0)
>  Making operation type 8 opaque [module /[Default VFS]/]
>  Accepting operation type 8 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #9 (type 9, layer 0)
>  Making operation type 9 opaque [module /[Default VFS]/]
>  Accepting operation type 9 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #10 (type 10, layer 0)
>  Making operation type 10 opaque [module /[Default VFS]/]
>  Accepting operation type 10 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #11 (type 11, layer 0)
>  Making operation type 11 opaque [module /[Default VFS]/]
>  Accepting operation type 11 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #12 (type 12, layer 0)
>  Making operation type 12 opaque [module /[Default VFS]/]
>  Accepting operation type 12 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #13 (type 13, layer 0)
>  Making operation type 13 opaque [module /[Default VFS]/]
>  Accepting operation type 13 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #14 (type 14, layer 0)
>  Making operation type 14 opaque [module /[Default VFS]/]
>  Accepting operation type 14 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #15 (type 15, layer 0)
>  Making operation type 15 opaque [module /[Default VFS]/]
>  Accepting operation type 15 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #16 (type 16, layer 0)
>  Making operation type 16 opaque [module /[Default VFS]/]
>  Accepting operation type 16 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #17 (type 17, layer 0)
>  Making operation type 17 opaque [module /[Default VFS]/]
>  Accepting operation type 17 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #18 (type 18, layer 0)
>  Making operation type 18 opaque [module /[Default VFS]/]
>  Accepting operation type 18 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #19 (type 19, layer 0)
>  Making operation type 19 opaque [module /[Default VFS]/]
>  Accepting operation type 19 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #20 (type 20, layer 0)
>  Making operation type 20 opaque [module /[Default VFS]/]
>  Accepting operation type 20 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #21 (type 21, layer 0)
>  Making operation type 21 opaque [module /[Default VFS]/]
>  Accepting operation type 21 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #22 (type 22, layer 0)
>  Making operation type 22 opaque [module /[Default VFS]/]
>  Accepting operation type 22 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #23 (type 23, layer 0)
>  Making operation type 23 opaque [module /[Default VFS]/]
>  Accepting operation type 23 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #24 (type 24, layer 0)
>  Making operation type 24 opaque [module /[Default VFS]/]
>  Accepting operation type 24 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #25 (type 25, layer 0)
>  Making operation type 25 opaque [module /[Default VFS]/]
>  Accepting operation type 25 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #26 (type 26, layer 0)
>  Making operation type 26 opaque [module /[Default VFS]/]
>  Accepting operation type 26 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #27 (type 27, layer 0)
>  Making operation type 27 opaque [module /[Default VFS]/]
>  Accepting operation type 27 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #28 (type 28, layer 0)
>  Making operation type 28 opaque [module /[Default VFS]/]
>  Accepting operation type 28 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #29 (type 29, layer 0)
>  Making operation type 29 opaque [module /[Default VFS]/]
>  Accepting operation type 29 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #30 (type 30, layer 0)
>  Making operation type 30 opaque [module /[Default VFS]/]
>  Accepting operation type 30 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #31 (type 31, layer 0)
>  Making operation type 31 opaque [module /[Default VFS]/]
>  Accepting operation type 31 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #32 (type 32, layer 0)
>  Making operation type 32 opaque [module /[Default VFS]/]
>  Accepting operation type 32 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #33 (type 33, layer 0)
>  Making operation type 33 opaque [module /[Default VFS]/]
>  Accepting operation type 33 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #34 (type 34, layer 0)
>  Making operation type 34 opaque [module /[Default VFS]/]
>  Accepting operation type 34 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #35 (type 35, layer 0)
>  Making operation type 35 opaque [module /[Default VFS]/]
>  Accepting operation type 35 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #36 (type 36, layer 0)
>  Making operation type 36 opaque [module /[Default VFS]/]
>  Accepting operation type 36 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #37 (type 37, layer 0)
>  Making operation type 37 opaque [module /[Default VFS]/]
>  Accepting operation type 37 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #38 (type 38, layer 0)
>  Making operation type 38 opaque [module /[Default VFS]/]
>  Accepting operation type 38 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #39 (type 39, layer 0)
>  Making operation type 39 opaque [module /[Default VFS]/]
>  Accepting operation type 39 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #40 (type 40, layer 0)
>  Making operation type 40 opaque [module /[Default VFS]/]
>  Accepting operation type 40 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #41 (type 41, layer 0)
>  Making operation type 41 opaque [module /[Default VFS]/]
>  Accepting operation type 41 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #42 (type 42, layer 0)
>  Making operation type 42 opaque [module /[Default VFS]/]
>  Accepting operation type 42 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #43 (type 43, layer 0)
>  Making operation type 43 opaque [module /[Default VFS]/]
>  Accepting operation type 43 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #44 (type 44, layer 0)
>  Making operation type 44 opaque [module /[Default VFS]/]
>  Accepting operation type 44 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #45 (type 45, layer 0)
>  Making operation type 45 opaque [module /[Default VFS]/]
>  Accepting operation type 45 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #46 (type 46, layer 0)
>  Making operation type 46 opaque [module /[Default VFS]/]
>  Accepting operation type 46 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #47 (type 47, layer 0)
>  Making operation type 47 opaque [module /[Default VFS]/]
>  Accepting operation type 47 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #48 (type 48, layer 0)
>  Making operation type 48 opaque [module /[Default VFS]/]
>  Accepting operation type 48 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #49 (type 49, layer 0)
>  Making operation type 49 opaque [module /[Default VFS]/]
>  Accepting operation type 49 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #50 (type 50, layer 0)
>  Making operation type 50 opaque [module /[Default VFS]/]
>  Accepting operation type 50 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #51 (type 51, layer 0)
>  Making operation type 51 opaque [module /[Default VFS]/]
>  Accepting operation type 51 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #52 (type 52, layer 0)
>  Making operation type 52 opaque [module /[Default VFS]/]
>  Accepting operation type 52 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #53 (type 53, layer 0)
>  Making operation type 53 opaque [module /[Default VFS]/]
>  Accepting operation type 53 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #54 (type 54, layer 0)
>  Making operation type 54 opaque [module /[Default VFS]/]
>  Accepting operation type 54 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #55 (type 55, layer 0)
>  Making operation type 55 opaque [module /[Default VFS]/]
>  Accepting operation type 55 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #56 (type 56, layer 0)
>  Making operation type 56 opaque [module /[Default VFS]/]
>  Accepting operation type 56 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #57 (type 57, layer 0)
>  Making operation type 57 opaque [module /[Default VFS]/]
>  Accepting operation type 57 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #58 (type 58, layer 0)
>  Making operation type 58 opaque [module /[Default VFS]/]
>  Accepting operation type 58 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #59 (type 59, layer 0)
>  Making operation type 59 opaque [module /[Default VFS]/]
>  Accepting operation type 59 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #60 (type 60, layer 0)
>  Making operation type 60 opaque [module /[Default VFS]/]
>  Accepting operation type 60 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #61 (type 61, layer 0)
>  Making operation type 61 opaque [module /[Default VFS]/]
>  Accepting operation type 61 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #62 (type 62, layer 0)
>  Making operation type 62 opaque [module /[Default VFS]/]
>  Accepting operation type 62 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #63 (type 63, layer 0)
>  Making operation type 63 opaque [module /[Default VFS]/]
>  Accepting operation type 63 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #64 (type 64, layer 0)
>  Making operation type 64 opaque [module /[Default VFS]/]
>  Accepting operation type 64 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #65 (type 65, layer 0)
>  Making operation type 65 opaque [module /[Default VFS]/]
>  Accepting operation type 65 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #66 (type 66, layer 0)
>  Making operation type 66 opaque [module /[Default VFS]/]
>  Accepting operation type 66 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #67 (type 67, layer 0)
>  Making operation type 67 opaque [module /[Default VFS]/]
>  Accepting operation type 67 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #68 (type 68, layer 0)
>  Making operation type 68 opaque [module /[Default VFS]/]
>  Accepting operation type 68 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #69 (type 69, layer 0)
>  Making operation type 69 opaque [module /[Default VFS]/]
>  Accepting operation type 69 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #70 (type 70, layer 0)
>  Making operation type 70 opaque [module /[Default VFS]/]
>  Accepting operation type 70 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #71 (type 71, layer 0)
>  Making operation type 71 opaque [module /[Default VFS]/]
>  Accepting operation type 71 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #72 (type 72, layer 0)
>  Making operation type 72 opaque [module /[Default VFS]/]
>  Accepting operation type 72 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #73 (type 73, layer 0)
>  Making operation type 73 opaque [module /[Default VFS]/]
>  Accepting operation type 73 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #74 (type 74, layer 0)
>  Making operation type 74 opaque [module /[Default VFS]/]
>  Accepting operation type 74 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #75 (type 75, layer 0)
>  Making operation type 75 opaque [module /[Default VFS]/]
>  Accepting operation type 75 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #76 (type 76, layer 0)
>  Making operation type 76 opaque [module /[Default VFS]/]
>  Accepting operation type 76 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #77 (type 77, layer 0)
>  Making operation type 77 opaque [module /[Default VFS]/]
>  Accepting operation type 77 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #78 (type 78, layer 0)
>  Making operation type 78 opaque [module /[Default VFS]/]
>  Accepting operation type 78 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #79 (type 79, layer 0)
>  Making operation type 79 opaque [module /[Default VFS]/]
>  Accepting operation type 79 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #80 (type 80, layer 0)
>  Making operation type 80 opaque [module /[Default VFS]/]
>  Accepting operation type 80 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #81 (type 81, layer 0)
>  Making operation type 81 opaque [module /[Default VFS]/]
>  Accepting operation type 81 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #82 (type 82, layer 0)
>  Making operation type 82 opaque [module /[Default VFS]/]
>  Accepting operation type 82 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #83 (type 83, layer 0)
>  Making operation type 83 opaque [module /[Default VFS]/]
>  Accepting operation type 83 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #84 (type 84, layer 0)
>  Making operation type 84 opaque [module /[Default VFS]/]
>  Accepting operation type 84 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #85 (type 85, layer 0)
>  Making operation type 85 opaque [module /[Default VFS]/]
>  Accepting operation type 85 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #86 (type 86, layer 0)
>  Making operation type 86 opaque [module /[Default VFS]/]
>  Accepting operation type 86 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #87 (type 87, layer 0)
>  Making operation type 87 opaque [module /[Default VFS]/]
>  Accepting operation type 87 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #88 (type 88, layer 0)
>  Making operation type 88 opaque [module /[Default VFS]/]
>  Accepting operation type 88 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #89 (type 89, layer 0)
>  Making operation type 89 opaque [module /[Default VFS]/]
>  Accepting operation type 89 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #90 (type 90, layer 0)
>  Making operation type 90 opaque [module /[Default VFS]/]
>  Accepting operation type 90 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #91 (type 91, layer 0)
>  Making operation type 91 opaque [module /[Default VFS]/]
>  Accepting operation type 91 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #92 (type 92, layer 0)
>  Making operation type 92 opaque [module /[Default VFS]/]
>  Accepting operation type 92 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #93 (type 93, layer 0)
>  Making operation type 93 opaque [module /[Default VFS]/]
>  Accepting operation type 93 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #94 (type 94, layer 0)
>  Making operation type 94 opaque [module /[Default VFS]/]
>  Accepting operation type 94 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #95 (type 95, layer 0)
>  Making operation type 95 opaque [module /[Default VFS]/]
>  Accepting operation type 95 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #96 (type 96, layer 0)
>  Making operation type 96 opaque [module /[Default VFS]/]
>  Accepting operation type 96 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #97 (type 97, layer 0)
>  Making operation type 97 opaque [module /[Default VFS]/]
>  Accepting operation type 97 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #98 (type 98, layer 0)
>  Making operation type 98 opaque [module /[Default VFS]/]
>  Accepting operation type 98 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #99 (type 99, layer 0)
>  Making operation type 99 opaque [module /[Default VFS]/]
>  Accepting operation type 99 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #100 (type 100, layer 0)
>  Making operation type 100 opaque [module /[Default VFS]/]
>  Accepting operation type 100 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #101 (type 101, layer 0)
>  Making operation type 101 opaque [module /[Default VFS]/]
>  Accepting operation type 101 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/vfs.c:vfs_init_custom(193)
>  Checking operation #102 (type 102, layer 0)
>  Making operation type 102 opaque [module /[Default VFS]/]
>  Accepting operation type 102 from module /[Default VFS]/
>[2009/10/30 19:31:29,  5] smbd/connection.c:claim_connection(142)
>  claiming [IPC$]
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100)
>  Locking key 0E3F0000020000004950
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129)
>  Allocated locked data 0x0x20c59eb0
>[2009/10/30 19:31:29, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42)
>  Unlocking key 0E3F0000020000004950
>[2009/10/30 19:31:29, 10] smbd/share_access.c:user_ok_token(234)
>  user_ok_token: share IPC$ is ok for unix user root
>[2009/10/30 19:31:29, 10] smbd/share_access.c:is_share_read_only_for_token(279)
>  is_share_read_only_for_user: share IPC$ is read-only for unix user root
>[2009/10/30 19:31:29, 10] lib/util_seaccess.c:se_map_generic(58)
>  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: maximum password age, val: -1
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_username(572)
>  pdb_set_username: setting username root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_domain(595)
>  pdb_set_domain: setting domain OLDJUNK, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_nt_username(618)
>  pdb_set_nt_username: setting nt username , was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_fullname(641)
>  pdb_set_full_name: setting full name Charlie &, was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_homedir(734)
>  pdb_set_homedir: setting home dir \\oldjunk\root, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(710)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_logon_script(664)
>  pdb_set_logon_script: setting logon script , was 
>[2009/10/30 19:31:29,  4] lib/substitute.c:automount_server(505)
>  Home server: oldjunk
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_profile_path(687)
>  pdb_set_profile_path: setting profile path \\oldjunk\root\profile, was 
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_workstations(777)
>  pdb_set_workstations: setting workstations , was 
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29, 10] lib/account_pol.c:account_policy_get(333)
>  account_policy_get: name: password history, val: 0
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29, 10] passdb/pdb_get_set.c:pdb_set_user_sid(501)
>  pdb_set_user_sid: setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001
>[2009/10/30 19:31:29, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-3710583579-4186145173-3854701597-1001 from rid 1001
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(528)
>  NT user token of user S-1-5-21-3710583579-4186145173-3854701597-1001
>  contains 7 SIDs
>  SID[  0]: S-1-5-21-3710583579-4186145173-3854701597-1001
>  SID[  1]: S-1-22-2-0
>  SID[  2]: S-1-1-0
>  SID[  3]: S-1-5-2
>  SID[  4]: S-1-5-11
>  SID[  5]: S-1-22-2-5
>  SID[  6]: S-1-22-1-0
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 2 supplementary groups
>  Group[  0]: 0
>  Group[  1]: 5
>[2009/10/30 19:31:29,  5] smbd/uid.c:change_to_user(355)
>  change_to_user uid=(0,0) gid=(0,0)
>[2009/10/30 19:31:29,  3] smbd/service.c:make_connection_snum(1119)
>  dadsdesktop (10.0.2.149) connect to service IPC$ initially as user root (uid=0, gid=0) (pid 16142)
>[2009/10/30 19:31:29,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_nt_user_token(522)
>  NT user token: (NULL)
>[2009/10/30 19:31:29,  5] auth/token_util.c:debug_unix_user_token(548)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/30 19:31:29,  5] smbd/uid.c:change_to_root_user(370)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/10/30 19:31:29,  3] smbd/reply.c:reply_tcon_and_X(794)
>  tconX service=IPC$ 
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(645)
>[2009/10/30 19:31:29,  5] lib/util.c:show_msg(655)
>  size=56
>  smb_com=0x75
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=2
>  smb_pid=65279
>  smb_uid=100
>  smb_mid=58112
>  smt_wct=7
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    1 (0x1)
>  smb_vwv[ 3]=  511 (0x1FF)
>  smb_vwv[ 4]=    0 (0x0)
>  smb_vwv[ 5]=  511 (0x1FF)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_bcc=7
>[2009/10/30 19:31:29, 10] lib/util.c:dump_data(2233)
>  [000] 49 50 43 00 00 00 00                              IPC....

Actions: View

Attachments on bug 6862: 4908 | 4909 | 4910