Attachment 3140 Details for Bug 5260 – computer's samba log

computer's samba log

vwd-df-util1 (text/plain), 159.22 KB, created by Jorge Adrian Salaices on 2008-02-13 20:27:23 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jorge Adrian Salaices

Created: 2008-02-13 20:27:23 UTC

Size: 159.22 KB

patch

obsolete

>[2008/02/13 19:59:22, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 19:59:22, 5] smbd/reply.c:reply_special(371)
>  init msg_type=0x81 msg_flags=0x0
>[2008/02/13 19:59:22, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x85
>[2008/02/13 19:59:22, 3] smbd/process.c:process_smb(1068)
>  Transaction 1 of length 137
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=133
>  smb_com=0x72
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51283
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=0
>  smb_mid=0
>  smt_wct=0
>  smb_bcc=98
>[2008/02/13 19:59:22, 3] smbd/process.c:switch_message(926)
>  switch message SMBnegprot (pid 23620) conn 0x0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:22, 3] smbd/negprot.c:reply_negprot(505)
>  Requested protocol [PC NETWORK PROGRAM 1.0]
>[2008/02/13 19:59:22, 3] smbd/negprot.c:reply_negprot(505)
>  Requested protocol [LANMAN1.0]
>[2008/02/13 19:59:22, 3] smbd/negprot.c:reply_negprot(505)
>  Requested protocol [Windows for Workgroups 3.1a]
>[2008/02/13 19:59:22, 3] smbd/negprot.c:reply_negprot(505)
>  Requested protocol [LM1.2X002]
>[2008/02/13 19:59:22, 3] smbd/negprot.c:reply_negprot(505)
>  Requested protocol [LANMAN2.1]
>[2008/02/13 19:59:22, 3] smbd/negprot.c:reply_negprot(505)
>  Requested protocol [NT LM 0.12]
>[2008/02/13 19:59:22, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 19:59:22, 5] smbd/connection.c:claim_connection(182)
>  claiming  0
>[2008/02/13 19:59:22, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 19:59:22, 3] smbd/negprot.c:reply_nt1(364)
>  using SPNEGO
>[2008/02/13 19:59:22, 3] smbd/negprot.c:reply_negprot(606)
>  Selected protocol NT LM 0.12
>[2008/02/13 19:59:22, 5] smbd/negprot.c:reply_negprot(612)
>  negprot index=5
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=127
>  smb_com=0x72
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  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]=17408 (0x4400)
>  smb_vwv[ 8]=   92 (0x5C)
>  smb_vwv[ 9]=64768 (0xFD00)
>  smb_vwv[10]=33011 (0x80F3)
>  smb_vwv[11]=  128 (0x80)
>  smb_vwv[12]=34361 (0x8639)
>  smb_vwv[13]=44343 (0xAD37)
>  smb_vwv[14]=51310 (0xC86E)
>  smb_vwv[15]=26625 (0x6801)
>  smb_vwv[16]=    1 (0x1)
>  smb_bcc=58
>[2008/02/13 19:59:22, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0xec
>[2008/02/13 19:59:22, 3] smbd/process.c:process_smb(1068)
>  Transaction 2 of length 240
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=236
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=0
>  smb_mid=64
>  smt_wct=12
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=  236 (0xEC)
>  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=177
>[2008/02/13 19:59:22, 3] smbd/process.c:switch_message(926)
>  switch message SMBsesssetupX (pid 23620) conn 0x0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244)
>  wct=12 flg2=0xc807
>[2008/02/13 19:59:22, 2] smbd/sesssetup.c:setup_new_vc_session(1200)
>  setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources.
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1029)
>  Doing spnego session setup
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1060)
>  NativeOS=[Windows 2002 Service Pack 2 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
>[2008/02/13 19:59:22, 5] smbd/sesssetup.c:parse_spnego_mechanisms(667)
>  parse_spnego_mechanisms: Got OID 1 3 6 1 4 1 311 2 2 10
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_spnego_negotiate(697)
>  reply_spnego_negotiate: Got secblob of size 40
>[2008/02/13 19:59:22, 5] auth/auth.c:make_auth_context_subsystem(483)
>  Making default auth method list for DC, security=user, encrypt passwords = yes
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend sam
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'sam'
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend sam_ignoredomain
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'sam_ignoredomain'
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend unix
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'unix'
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend winbind
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'winbind'
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend smbserver
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'smbserver'
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend trustdomain
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'trustdomain'
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend ntdomain
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'ntdomain'
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend guest
>[2008/02/13 19:59:22, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'guest'
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match guest
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method guest has a valid init
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match sam
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method sam has a valid init
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match winbind:trustdomain
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match trustdomain
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method trustdomain has a valid init
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method winbind has a valid init
>[2008/02/13 19:59:22, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(63)
>  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_128
>    NTLMSSP_NEGOTIATE_KEY_EXCH
>    NTLMSSP_NEGOTIATE_56
>[2008/02/13 19:59:22, 5] auth/auth.c:get_ntlm_challenge(97)
>  auth_get_challenge: module guest did not want to specify a challenge
>[2008/02/13 19:59:22, 5] auth/auth.c:get_ntlm_challenge(97)
>  auth_get_challenge: module sam did not want to specify a challenge
>[2008/02/13 19:59:22, 5] auth/auth.c:get_ntlm_challenge(97)
>  auth_get_challenge: module winbind did not want to specify a challenge
>[2008/02/13 19:59:22, 5] auth/auth.c:get_ntlm_challenge(137)
>  auth_context challenge created by random
>[2008/02/13 19:59:22, 5] auth/auth.c:get_ntlm_challenge(138)
>  challenge is: 
>[2008/02/13 19:59:22, 5] lib/util.c:dump_data(2283)
>  [000] B3 A1 4C EB 98 9B 3F 2E                           ..L...?. 
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=344
>  smb_com=0x73
>  smb_rcls=22
>  smb_reh=0
>  smb_err=49152
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=100
>  smb_mid=64
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=  221 (0xDD)
>  smb_bcc=301
>[2008/02/13 19:59:22, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x11a
>[2008/02/13 19:59:22, 3] smbd/process.c:process_smb(1068)
>  Transaction 3 of length 286
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=282
>  smb_com=0x73
>  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=128
>  smt_wct=12
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=  282 (0x11A)
>  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]=  121 (0x79)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_vwv[10]=  212 (0xD4)
>  smb_vwv[11]=40960 (0xA000)
>  smb_bcc=223
>[2008/02/13 19:59:22, 3] smbd/process.c:switch_message(926)
>  switch message SMBsesssetupX (pid 23620) conn 0x0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244)
>  wct=12 flg2=0xc807
>[2008/02/13 19:59:22, 2] smbd/sesssetup.c:setup_new_vc_session(1200)
>  setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources.
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1029)
>  Doing spnego session setup
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1060)
>  NativeOS=[Windows 2002 Service Pack 2 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
>[2008/02/13 19:59:22, 3] libsmb/ntlmssp.c:ntlmssp_server_auth(739)
>  Got user=[] domain=[] workstation=[VWD-DF-UTIL1] len1=1 len2=0
>[2008/02/13 19:59:22, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 19:59:22, 5] auth/auth_util.c:make_user_info_map(161)
>  make_user_info_map: Mapping user []\[] from workstation [VWD-DF-UTIL1]
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] auth/auth_util.c:is_trusted_domain(2198)
>  is_trusted_domain: Checking for domain trust with [AVATAS.COM]
>[2008/02/13 19:59:22, 5] passdb/secrets.c:secrets_fetch_trusted_domain_password(473)
>  secrets_fetch failed!
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] libsmb/trustdom_cache.c:trustdom_cache_fetch(184)
>  no entry for trusted domain AVATAS.COM found.
>[2008/02/13 19:59:22, 5] auth/auth_util.c:make_user_info(75)
>  attempting to make a user_info for  ()
>[2008/02/13 19:59:22, 5] auth/auth_util.c:make_user_info(85)
>  making strings for 's user_info struct
>[2008/02/13 19:59:22, 5] auth/auth_util.c:make_user_info(117)
>  making blobs for 's user_info struct
>[2008/02/13 19:59:22, 3] auth/auth.c:check_ntlm_password(221)
>  check_ntlm_password:  Checking password for unmapped user []\[]@[VWD-DF-UTIL1] with the new password interface
>[2008/02/13 19:59:22, 3] auth/auth.c:check_ntlm_password(224)
>  check_ntlm_password:  mapped user is: [AVATAS.COM]\[]@[VWD-DF-UTIL1]
>[2008/02/13 19:59:22, 5] lib/util.c:dump_data(2283)
>  [000] B3 A1 4C EB 98 9B 3F 2E                           ..L...?. 
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(objectClass=sambaGroupMapping)(gidNumber=99))], scope => [2]
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_close(1085)
>  The connection to the LDAP server was closed
>[2008/02/13 19:59:22, 2] lib/smbldap.c:smbldap_open_connection(786)
>  smbldap_open_connection: connection opened
>[2008/02/13 19:59:22, 3] lib/smbldap.c:smbldap_check_root_dse(1694)
>  smbldap_check_root_dse: Expected one rootDSE, got 0
>[2008/02/13 19:59:22, 3] lib/smbldap.c:smbldap_connect_system(997)
>  ldap_connect_system: succesful connection to the LDAP server
>[2008/02/13 19:59:22, 4] lib/smbldap.c:smbldap_open(1065)
>  The LDAP server is succesfully connected
>[2008/02/13 19:59:22, 4] passdb/pdb_ldap.c:ldapsam_getgroup(2235)
>  ldapsam_getgroup: Did not find group
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 4] lib/substitute.c:automount_server(407)
>  Home server: lsv-df-dc1
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] auth/auth.c:check_ntlm_password(270)
>  check_ntlm_password: guest authentication for user [] succeeded
>[2008/02/13 19:59:22, 5] auth/auth.c:check_ntlm_password(309)
>  check_ntlm_password:  guest authentication for user [] -> [] -> [nobody] succeeded
>[2008/02/13 19:59:22, 5] auth/auth_util.c:free_user_info(2045)
>  attempting to free (and zero) a user_info structure
>[2008/02/13 19:59:22, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089)
>  fetch gid from cache 544 -> S-1-5-32-544
>[2008/02/13 19:59:22, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089)
>  fetch gid from cache 10000 -> S-1-5-32-545
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(|(objectclass=sambaGroupMapping)(sambaGroupType=4))(|(sambaSIDList=S-1-5-21-2953885461-2572836322-187695268-501)(sambaSIDList=S-1-1-0)(sambaSIDList=S-1-5-2)(sambaSIDList=S-1-5-32-546)))], scope => [2]
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(|(objectclass=sambaGroupMapping)(sambaGroupType=4))(|(sambaSIDList=S-1-5-21-2953885461-2572836322-187695268-501)(sambaSIDList=S-1-1-0)(sambaSIDList=S-1-5-2)(sambaSIDList=S-1-5-32-546)))], scope => [2]
>[2008/02/13 19:59:22, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2953885461-2572836322-187695268-501]
>[2008/02/13 19:59:22, 5] lib/privileges.c:get_privileges_for_sids(460)
>  get_privileges_for_sids: sid = S-1-1-0
>  Privilege set:
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2008/02/13 19:59:22, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2008/02/13 19:59:22, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-32-546]
>[2008/02/13 19:59:22, 3] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(338)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2008/02/13 19:59:22, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(63)
>  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_128
>    NTLMSSP_NEGOTIATE_KEY_EXCH
>    NTLMSSP_NEGOTIATE_56
>[2008/02/13 19:59:22, 3] smbd/password.c:register_vuid(280)
>  User name: nobody	Real name: Nobody
>[2008/02/13 19:59:22, 3] smbd/password.c:register_vuid(301)
>  UNIX uid 99 is UNIX user nobody, and will be vuid 101
>[2008/02/13 19:59:22, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=132
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=128
>  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=89
>[2008/02/13 19:59:22, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x56
>[2008/02/13 19:59:22, 3] smbd/process.c:process_smb(1068)
>  Transaction 4 of length 90
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=86
>  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=101
>  smb_mid=192
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=   86 (0x56)
>  smb_vwv[ 2]=    8 (0x8)
>  smb_vwv[ 3]=    1 (0x1)
>  smb_bcc=43
>[2008/02/13 19:59:22, 3] smbd/process.c:switch_message(926)
>  switch message SMBtconX (pid 23620) conn 0x0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:22, 4] smbd/reply.c:reply_tcon_and_X(506)
>  Client requested device type [?????] for share [IPC$]
>[2008/02/13 19:59:22, 5] smbd/service.c:make_connection(1205)
>  making a connection to 'normal' service ipc$
>[2008/02/13 19:59:22, 5] lib/username.c:Get_Pwnam_alloc(131)
>  Finding user nobody
>[2008/02/13 19:59:22, 5] lib/username.c:Get_Pwnam_internals(75)
>  Trying _Get_Pwnam(), username as lowercase is nobody
>[2008/02/13 19:59:22, 5] lib/username.c:Get_Pwnam_internals(108)
>  Get_Pwnam_internals did find user [nobody]!
>[2008/02/13 19:59:22, 3] smbd/service.c:make_connection_snum(806)
>  Connect path is '/tmp' for service [IPC$]
>[2008/02/13 19:59:22, 4] lib/sharesec.c:get_share_security(132)
>  get_share_security: using default secdesc for IPC$
>[2008/02/13 19:59:22, 3] lib/util_seaccess.c:se_access_check(250)
>[2008/02/13 19:59:22, 3] lib/util_seaccess.c:se_access_check(251)
>  se_access_check: user sid is S-1-5-21-2953885461-2572836322-187695268-501
>  se_access_check: also S-1-1-0
>  se_access_check: also S-1-5-2
>  se_access_check: also S-1-5-32-546
>[2008/02/13 19:59:22, 5] lib/util_seaccess.c:se_access_check(308)
>  se_access_check: access (2) granted.
>[2008/02/13 19:59:22, 3] smbd/vfs.c:vfs_init_default(95)
>  Initialising default vfs hooks
>[2008/02/13 19:59:22, 5] smbd/vfs.c:smb_register_vfs(85)
>  Successfully added vfs backend '/[Default VFS]/'
>[2008/02/13 19:59:22, 5] smbd/vfs.c:smb_register_vfs(85)
>  Successfully added vfs backend 'posixacl'
>[2008/02/13 19:59:22, 3] smbd/vfs.c:vfs_init_custom(128)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>  Successfully loaded vfs module [/[Default VFS]/] with the new modules system
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #0 (type 0, layer 0)
>  Making operation type 0 opaque [module /[Default VFS]/]
>  Accepting operation type 0 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #1 (type 1, layer 0)
>  Making operation type 1 opaque [module /[Default VFS]/]
>  Accepting operation type 1 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #2 (type 2, layer 0)
>  Making operation type 2 opaque [module /[Default VFS]/]
>  Accepting operation type 2 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #3 (type 3, layer 0)
>  Making operation type 3 opaque [module /[Default VFS]/]
>  Accepting operation type 3 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #4 (type 4, layer 0)
>  Making operation type 4 opaque [module /[Default VFS]/]
>  Accepting operation type 4 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #5 (type 5, layer 0)
>  Making operation type 5 opaque [module /[Default VFS]/]
>  Accepting operation type 5 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #6 (type 6, layer 0)
>  Making operation type 6 opaque [module /[Default VFS]/]
>  Accepting operation type 6 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #7 (type 7, layer 0)
>  Making operation type 7 opaque [module /[Default VFS]/]
>  Accepting operation type 7 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #8 (type 8, layer 0)
>  Making operation type 8 opaque [module /[Default VFS]/]
>  Accepting operation type 8 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #9 (type 9, layer 0)
>  Making operation type 9 opaque [module /[Default VFS]/]
>  Accepting operation type 9 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #10 (type 10, layer 0)
>  Making operation type 10 opaque [module /[Default VFS]/]
>  Accepting operation type 10 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #11 (type 11, layer 0)
>  Making operation type 11 opaque [module /[Default VFS]/]
>  Accepting operation type 11 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #12 (type 12, layer 0)
>  Making operation type 12 opaque [module /[Default VFS]/]
>  Accepting operation type 12 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #13 (type 13, layer 0)
>  Making operation type 13 opaque [module /[Default VFS]/]
>  Accepting operation type 13 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #14 (type 14, layer 0)
>  Making operation type 14 opaque [module /[Default VFS]/]
>  Accepting operation type 14 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #15 (type 15, layer 0)
>  Making operation type 15 opaque [module /[Default VFS]/]
>  Accepting operation type 15 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #16 (type 16, layer 0)
>  Making operation type 16 opaque [module /[Default VFS]/]
>  Accepting operation type 16 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #17 (type 17, layer 0)
>  Making operation type 17 opaque [module /[Default VFS]/]
>  Accepting operation type 17 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #18 (type 18, layer 0)
>  Making operation type 18 opaque [module /[Default VFS]/]
>  Accepting operation type 18 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #19 (type 19, layer 0)
>  Making operation type 19 opaque [module /[Default VFS]/]
>  Accepting operation type 19 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #20 (type 20, layer 0)
>  Making operation type 20 opaque [module /[Default VFS]/]
>  Accepting operation type 20 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #21 (type 21, layer 0)
>  Making operation type 21 opaque [module /[Default VFS]/]
>  Accepting operation type 21 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #22 (type 22, layer 0)
>  Making operation type 22 opaque [module /[Default VFS]/]
>  Accepting operation type 22 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #23 (type 23, layer 0)
>  Making operation type 23 opaque [module /[Default VFS]/]
>  Accepting operation type 23 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #24 (type 24, layer 0)
>  Making operation type 24 opaque [module /[Default VFS]/]
>  Accepting operation type 24 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #25 (type 25, layer 0)
>  Making operation type 25 opaque [module /[Default VFS]/]
>  Accepting operation type 25 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #26 (type 26, layer 0)
>  Making operation type 26 opaque [module /[Default VFS]/]
>  Accepting operation type 26 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #27 (type 27, layer 0)
>  Making operation type 27 opaque [module /[Default VFS]/]
>  Accepting operation type 27 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #28 (type 28, layer 0)
>  Making operation type 28 opaque [module /[Default VFS]/]
>  Accepting operation type 28 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #29 (type 29, layer 0)
>  Making operation type 29 opaque [module /[Default VFS]/]
>  Accepting operation type 29 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #30 (type 30, layer 0)
>  Making operation type 30 opaque [module /[Default VFS]/]
>  Accepting operation type 30 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #31 (type 31, layer 0)
>  Making operation type 31 opaque [module /[Default VFS]/]
>  Accepting operation type 31 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #32 (type 32, layer 0)
>  Making operation type 32 opaque [module /[Default VFS]/]
>  Accepting operation type 32 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #33 (type 33, layer 0)
>  Making operation type 33 opaque [module /[Default VFS]/]
>  Accepting operation type 33 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #34 (type 34, layer 0)
>  Making operation type 34 opaque [module /[Default VFS]/]
>  Accepting operation type 34 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #35 (type 35, layer 0)
>  Making operation type 35 opaque [module /[Default VFS]/]
>  Accepting operation type 35 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #36 (type 36, layer 0)
>  Making operation type 36 opaque [module /[Default VFS]/]
>  Accepting operation type 36 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #37 (type 37, layer 0)
>  Making operation type 37 opaque [module /[Default VFS]/]
>  Accepting operation type 37 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #38 (type 38, layer 0)
>  Making operation type 38 opaque [module /[Default VFS]/]
>  Accepting operation type 38 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #39 (type 39, layer 0)
>  Making operation type 39 opaque [module /[Default VFS]/]
>  Accepting operation type 39 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #40 (type 40, layer 0)
>  Making operation type 40 opaque [module /[Default VFS]/]
>  Accepting operation type 40 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #41 (type 41, layer 0)
>  Making operation type 41 opaque [module /[Default VFS]/]
>  Accepting operation type 41 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #42 (type 42, layer 0)
>  Making operation type 42 opaque [module /[Default VFS]/]
>  Accepting operation type 42 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #43 (type 43, layer 0)
>  Making operation type 43 opaque [module /[Default VFS]/]
>  Accepting operation type 43 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #44 (type 44, layer 0)
>  Making operation type 44 opaque [module /[Default VFS]/]
>  Accepting operation type 44 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #45 (type 45, layer 0)
>  Making operation type 45 opaque [module /[Default VFS]/]
>  Accepting operation type 45 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #46 (type 46, layer 0)
>  Making operation type 46 opaque [module /[Default VFS]/]
>  Accepting operation type 46 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #47 (type 47, layer 0)
>  Making operation type 47 opaque [module /[Default VFS]/]
>  Accepting operation type 47 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #48 (type 48, layer 0)
>  Making operation type 48 opaque [module /[Default VFS]/]
>  Accepting operation type 48 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #49 (type 49, layer 0)
>  Making operation type 49 opaque [module /[Default VFS]/]
>  Accepting operation type 49 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #50 (type 50, layer 0)
>  Making operation type 50 opaque [module /[Default VFS]/]
>  Accepting operation type 50 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #51 (type 51, layer 0)
>  Making operation type 51 opaque [module /[Default VFS]/]
>  Accepting operation type 51 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #52 (type 52, layer 0)
>  Making operation type 52 opaque [module /[Default VFS]/]
>  Accepting operation type 52 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #53 (type 53, layer 0)
>  Making operation type 53 opaque [module /[Default VFS]/]
>  Accepting operation type 53 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #54 (type 54, layer 0)
>  Making operation type 54 opaque [module /[Default VFS]/]
>  Accepting operation type 54 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #55 (type 55, layer 0)
>  Making operation type 55 opaque [module /[Default VFS]/]
>  Accepting operation type 55 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #56 (type 56, layer 0)
>  Making operation type 56 opaque [module /[Default VFS]/]
>  Accepting operation type 56 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #57 (type 57, layer 0)
>  Making operation type 57 opaque [module /[Default VFS]/]
>  Accepting operation type 57 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #58 (type 58, layer 0)
>  Making operation type 58 opaque [module /[Default VFS]/]
>  Accepting operation type 58 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #59 (type 59, layer 0)
>  Making operation type 59 opaque [module /[Default VFS]/]
>  Accepting operation type 59 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #60 (type 60, layer 0)
>  Making operation type 60 opaque [module /[Default VFS]/]
>  Accepting operation type 60 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #61 (type 61, layer 0)
>  Making operation type 61 opaque [module /[Default VFS]/]
>  Accepting operation type 61 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #62 (type 62, layer 0)
>  Making operation type 62 opaque [module /[Default VFS]/]
>  Accepting operation type 62 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #63 (type 63, layer 0)
>  Making operation type 63 opaque [module /[Default VFS]/]
>  Accepting operation type 63 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #64 (type 64, layer 0)
>  Making operation type 64 opaque [module /[Default VFS]/]
>  Accepting operation type 64 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #65 (type 65, layer 0)
>  Making operation type 65 opaque [module /[Default VFS]/]
>  Accepting operation type 65 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #66 (type 66, layer 0)
>  Making operation type 66 opaque [module /[Default VFS]/]
>  Accepting operation type 66 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #67 (type 67, layer 0)
>  Making operation type 67 opaque [module /[Default VFS]/]
>  Accepting operation type 67 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #68 (type 68, layer 0)
>  Making operation type 68 opaque [module /[Default VFS]/]
>  Accepting operation type 68 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #69 (type 69, layer 0)
>  Making operation type 69 opaque [module /[Default VFS]/]
>  Accepting operation type 69 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #70 (type 70, layer 0)
>  Making operation type 70 opaque [module /[Default VFS]/]
>  Accepting operation type 70 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #71 (type 71, layer 0)
>  Making operation type 71 opaque [module /[Default VFS]/]
>  Accepting operation type 71 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #72 (type 72, layer 0)
>  Making operation type 72 opaque [module /[Default VFS]/]
>  Accepting operation type 72 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #73 (type 73, layer 0)
>  Making operation type 73 opaque [module /[Default VFS]/]
>  Accepting operation type 73 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #74 (type 74, layer 0)
>  Making operation type 74 opaque [module /[Default VFS]/]
>  Accepting operation type 74 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #75 (type 75, layer 0)
>  Making operation type 75 opaque [module /[Default VFS]/]
>  Accepting operation type 75 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #76 (type 76, layer 0)
>  Making operation type 76 opaque [module /[Default VFS]/]
>  Accepting operation type 76 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #77 (type 77, layer 0)
>  Making operation type 77 opaque [module /[Default VFS]/]
>  Accepting operation type 77 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #78 (type 78, layer 0)
>  Making operation type 78 opaque [module /[Default VFS]/]
>  Accepting operation type 78 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #79 (type 79, layer 0)
>  Making operation type 79 opaque [module /[Default VFS]/]
>  Accepting operation type 79 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #80 (type 80, layer 0)
>  Making operation type 80 opaque [module /[Default VFS]/]
>  Accepting operation type 80 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #81 (type 81, layer 0)
>  Making operation type 81 opaque [module /[Default VFS]/]
>  Accepting operation type 81 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #82 (type 82, layer 0)
>  Making operation type 82 opaque [module /[Default VFS]/]
>  Accepting operation type 82 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #83 (type 83, layer 0)
>  Making operation type 83 opaque [module /[Default VFS]/]
>  Accepting operation type 83 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #84 (type 84, layer 0)
>  Making operation type 84 opaque [module /[Default VFS]/]
>  Accepting operation type 84 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #85 (type 85, layer 0)
>  Making operation type 85 opaque [module /[Default VFS]/]
>  Accepting operation type 85 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #86 (type 86, layer 0)
>  Making operation type 86 opaque [module /[Default VFS]/]
>  Accepting operation type 86 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #87 (type 87, layer 0)
>  Making operation type 87 opaque [module /[Default VFS]/]
>  Accepting operation type 87 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #88 (type 88, layer 0)
>  Making operation type 88 opaque [module /[Default VFS]/]
>  Accepting operation type 88 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #89 (type 89, layer 0)
>  Making operation type 89 opaque [module /[Default VFS]/]
>  Accepting operation type 89 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #90 (type 90, layer 0)
>  Making operation type 90 opaque [module /[Default VFS]/]
>  Accepting operation type 90 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #91 (type 91, layer 0)
>  Making operation type 91 opaque [module /[Default VFS]/]
>  Accepting operation type 91 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #92 (type 92, layer 0)
>  Making operation type 92 opaque [module /[Default VFS]/]
>  Accepting operation type 92 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #93 (type 93, layer 0)
>  Making operation type 93 opaque [module /[Default VFS]/]
>  Accepting operation type 93 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #94 (type 94, layer 0)
>  Making operation type 94 opaque [module /[Default VFS]/]
>  Accepting operation type 94 from module /[Default VFS]/
>[2008/02/13 19:59:22, 5] smbd/connection.c:claim_connection(182)
>  claiming IPC$ 0
>[2008/02/13 19:59:22, 4] lib/sharesec.c:get_share_security(132)
>  get_share_security: using default secdesc for IPC$
>[2008/02/13 19:59:22, 3] lib/util_seaccess.c:se_access_check(250)
>[2008/02/13 19:59:22, 3] lib/util_seaccess.c:se_access_check(251)
>  se_access_check: user sid is S-1-5-21-2953885461-2572836322-187695268-501
>  se_access_check: also S-1-1-0
>  se_access_check: also S-1-5-2
>  se_access_check: also S-1-5-32-546
>[2008/02/13 19:59:22, 5] lib/util_seaccess.c:se_access_check(308)
>  se_access_check: access (1) granted.
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (99, 99) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(454)
>  NT user token of user S-1-5-21-2953885461-2572836322-187695268-501
>  contains 4 SIDs
>  SID[  0]: S-1-5-21-2953885461-2572836322-187695268-501
>  SID[  1]: S-1-1-0
>  SID[  2]: S-1-5-2
>  SID[  3]: S-1-5-32-546
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 99
>  Primary group is 99 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] smbd/uid.c:change_to_user(273)
>  change_to_user uid=(99,99) gid=(0,99)
>[2008/02/13 19:59:22, 3] smbd/service.c:make_connection_snum(1033)
>  vwd-df-util1 (10.0.1.22) connect to service IPC$ initially as user nobody (uid=99, gid=99) (pid 23620)
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:22, 3] smbd/reply.c:reply_tcon_and_X(574)
>  tconX service=IPC$ 
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=56
>  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=101
>  smb_mid=192
>  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
>[2008/02/13 19:59:22, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x70
>[2008/02/13 19:59:22, 3] smbd/process.c:process_smb(1068)
>  Transaction 5 of length 116
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=112
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=4
>  smb_uid=101
>  smb_mid=256
>  smt_wct=15
>  smb_vwv[ 0]=   44 (0x2C)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]= 4096 (0x1000)
>  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]=   44 (0x2C)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=   16 (0x10)
>  smb_bcc=47
>[2008/02/13 19:59:22, 3] smbd/process.c:switch_message(926)
>  switch message SMBtrans2 (pid 23620) conn 0x916c790
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (99, 99) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(454)
>  NT user token of user S-1-5-21-2953885461-2572836322-187695268-501
>  contains 4 SIDs
>  SID[  0]: S-1-5-21-2953885461-2572836322-187695268-501
>  SID[  1]: S-1-1-0
>  SID[  2]: S-1-5-2
>  SID[  3]: S-1-5-32-546
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 99
>  Primary group is 99 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] smbd/uid.c:change_to_user(273)
>  change_to_user uid=(99,99) gid=(0,99)
>[2008/02/13 19:59:22, 4] smbd/vfs.c:vfs_ChDir(665)
>  vfs_ChDir to /tmp
>[2008/02/13 19:59:22, 3] smbd/msdfs.c:get_referred_path(624)
>  get_referred_path: |shoptest| in dfs path \lsv-df-dc1\shoptest is not a dfs root.
>[2008/02/13 19:59:22, 3] smbd/error.c:error_packet_set(106)
>  error packet at smbd/trans2.c(6205) cmd=50 (SMBtrans2) NT_STATUS_NOT_FOUND
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=35
>  smb_com=0x32
>  smb_rcls=37
>  smb_reh=2
>  smb_err=49152
>  smb_flg=136
>  smb_flg2=51265
>  smb_tid=1
>  smb_pid=4
>  smb_uid=101
>  smb_mid=256
>  smt_wct=0
>  smb_bcc=0
>[2008/02/13 19:59:22, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0xec
>[2008/02/13 19:59:22, 3] smbd/process.c:process_smb(1068)
>  Transaction 6 of length 240
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=236
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=0
>  smb_mid=320
>  smt_wct=12
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=  236 (0xEC)
>  smb_vwv[ 2]=16644 (0x4104)
>  smb_vwv[ 3]=   50 (0x32)
>  smb_vwv[ 4]=    1 (0x1)
>  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=177
>[2008/02/13 19:59:22, 3] smbd/process.c:switch_message(926)
>  switch message SMBsesssetupX (pid 23620) conn 0x0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244)
>  wct=12 flg2=0xc807
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1029)
>  Doing spnego session setup
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1060)
>  NativeOS=[Windows 2002 Service Pack 2 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
>[2008/02/13 19:59:22, 5] smbd/sesssetup.c:parse_spnego_mechanisms(667)
>  parse_spnego_mechanisms: Got OID 1 3 6 1 4 1 311 2 2 10
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_spnego_negotiate(697)
>  reply_spnego_negotiate: Got secblob of size 40
>[2008/02/13 19:59:22, 5] auth/auth.c:make_auth_context_subsystem(483)
>  Making default auth method list for DC, security=user, encrypt passwords = yes
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match guest
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method guest has a valid init
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match sam
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method sam has a valid init
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match winbind:trustdomain
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match trustdomain
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method trustdomain has a valid init
>[2008/02/13 19:59:22, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method winbind has a valid init
>[2008/02/13 19:59:22, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(63)
>  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_128
>    NTLMSSP_NEGOTIATE_KEY_EXCH
>    NTLMSSP_NEGOTIATE_56
>[2008/02/13 19:59:22, 5] auth/auth.c:get_ntlm_challenge(97)
>  auth_get_challenge: module guest did not want to specify a challenge
>[2008/02/13 19:59:22, 5] auth/auth.c:get_ntlm_challenge(97)
>  auth_get_challenge: module sam did not want to specify a challenge
>[2008/02/13 19:59:22, 5] auth/auth.c:get_ntlm_challenge(97)
>  auth_get_challenge: module winbind did not want to specify a challenge
>[2008/02/13 19:59:22, 5] auth/auth.c:get_ntlm_challenge(137)
>  auth_context challenge created by random
>[2008/02/13 19:59:22, 5] auth/auth.c:get_ntlm_challenge(138)
>  challenge is: 
>[2008/02/13 19:59:22, 5] lib/util.c:dump_data(2283)
>  [000] C9 48 6D 40 3C D9 60 44                           .Hm@<.`D 
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=344
>  smb_com=0x73
>  smb_rcls=22
>  smb_reh=0
>  smb_err=49152
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=102
>  smb_mid=320
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=  221 (0xDD)
>  smb_bcc=301
>[2008/02/13 19:59:22, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x174
>[2008/02/13 19:59:22, 3] smbd/process.c:process_smb(1068)
>  Transaction 7 of length 376
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=372
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=102
>  smb_mid=384
>  smt_wct=12
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=  372 (0x174)
>  smb_vwv[ 2]=16644 (0x4104)
>  smb_vwv[ 3]=   50 (0x32)
>  smb_vwv[ 4]=    1 (0x1)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=  210 (0xD2)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_vwv[10]=  212 (0xD4)
>  smb_vwv[11]=40960 (0xA000)
>  smb_bcc=313
>[2008/02/13 19:59:22, 3] smbd/process.c:switch_message(926)
>  switch message SMBsesssetupX (pid 23620) conn 0x0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244)
>  wct=12 flg2=0xc807
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1029)
>  Doing spnego session setup
>[2008/02/13 19:59:22, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1060)
>  NativeOS=[Windows 2002 Service Pack 2 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
>[2008/02/13 19:59:22, 3] libsmb/ntlmssp.c:ntlmssp_server_auth(739)
>  Got user=[asalaices] domain=[avatas.com] workstation=[VWD-DF-UTIL1] len1=24 len2=24
>[2008/02/13 19:59:22, 5] auth/auth_ntlmssp.c:auth_ntlmssp_set_challenge(69)
>  auth_context challenge set by NTLMSSP callback (NTLM2)
>[2008/02/13 19:59:22, 5] auth/auth_ntlmssp.c:auth_ntlmssp_set_challenge(70)
>  challenge is: 
>[2008/02/13 19:59:22, 5] lib/util.c:dump_data(2283)
>  [000] 96 34 97 E4 9A 8F 52 E2                           .4....R. 
>[2008/02/13 19:59:22, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 19:59:22, 4] smbd/map_username.c:map_username(111)
>  Scanning username map /etc/samba/smbusers
>[2008/02/13 19:59:22, 5] auth/auth_util.c:make_user_info_map(161)
>  make_user_info_map: Mapping user [avatas.com]\[asalaices] from workstation [VWD-DF-UTIL1]
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] auth/auth_util.c:is_trusted_domain(2198)
>  is_trusted_domain: Checking for domain trust with [avatas.com]
>[2008/02/13 19:59:22, 5] passdb/secrets.c:secrets_fetch_trusted_domain_password(473)
>  secrets_fetch failed!
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] libsmb/trustdom_cache.c:trustdom_cache_fetch(184)
>  no entry for trusted domain avatas.com found.
>[2008/02/13 19:59:22, 5] auth/auth_util.c:make_user_info(75)
>  attempting to make a user_info for asalaices (asalaices)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:make_user_info(85)
>  making strings for asalaices's user_info struct
>[2008/02/13 19:59:22, 5] auth/auth_util.c:make_user_info(117)
>  making blobs for asalaices's user_info struct
>[2008/02/13 19:59:22, 3] auth/auth.c:check_ntlm_password(221)
>  check_ntlm_password:  Checking password for unmapped user [avatas.com]\[asalaices]@[VWD-DF-UTIL1] with the new password interface
>[2008/02/13 19:59:22, 3] auth/auth.c:check_ntlm_password(224)
>  check_ntlm_password:  mapped user is: [avatas.com]\[asalaices]@[VWD-DF-UTIL1]
>[2008/02/13 19:59:22, 5] lib/util.c:dump_data(2283)
>  [000] 96 34 97 E4 9A 8F 52 E2                           .4....R. 
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [dc=avatas,dc=com], filter => [(&(uid=asalaices)(objectclass=sambaSamAccount))], scope => [2]
>[2008/02/13 19:59:22, 2] passdb/pdb_ldap.c:init_sam_from_ldap(545)
>  init_sam_from_ldap: Entry found for user: asalaices
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 5] passdb/login_cache.c:login_cache_init(41)
>  Opening cache file at /var/cache/samba/login_cache.tdb
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [sambaDomainName=AVATAS.COM,dc=avatas,dc=com], filter => [(objectclass=*)], scope => [0]
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 5] lib/username.c:Get_Pwnam_alloc(131)
>  Finding user asalaices
>[2008/02/13 19:59:22, 5] lib/username.c:Get_Pwnam_internals(75)
>  Trying _Get_Pwnam(), username as lowercase is asalaices
>[2008/02/13 19:59:22, 5] lib/username.c:Get_Pwnam_internals(108)
>  Get_Pwnam_internals did find user [asalaices]!
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(objectClass=sambaGroupMapping)(gidNumber=513))], scope => [2]
>[2008/02/13 19:59:22, 2] passdb/pdb_ldap.c:init_group_from_ldap(2158)
>  init_group_from_ldap: Entry found for group: 513
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] passdb/pdb_interface.c:lookup_global_sam_rid(1522)
>  lookup_global_sam_rid: looking up RID 513.
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [dc=avatas,dc=com], filter => [(&(sambaSID=S-1-5-21-2953885461-2572836322-187695268-513)(objectclass=sambaSamAccount))], scope => [2]
>[2008/02/13 19:59:22, 4] passdb/pdb_ldap.c:ldapsam_getsampwsid(1496)
>  ldapsam_getsampwsid: Unable to locate SID [S-1-5-21-2953885461-2572836322-187695268-513] count=0
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(objectClass=sambaGroupMapping)(sambaSID=S-1-5-21-2953885461-2572836322-187695268-513))], scope => [2]
>[2008/02/13 19:59:22, 2] passdb/pdb_ldap.c:init_group_from_ldap(2158)
>  init_group_from_ldap: Entry found for group: 513
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2008/02/13 19:59:22, 5] passdb/pdb_interface.c:pdb_default_lookup_rids(1643)
>  lookup_rids: Domain Users:2
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 4] libsmb/ntlm_check.c:ntlm_password_check(326)
>  ntlm_password_check: Checking NT MD4 password
>[2008/02/13 19:59:22, 4] auth/auth_sam.c:sam_account_ok(138)
>  sam_account_ok: Checking SMB password for user asalaices
>[2008/02/13 19:59:22, 5] auth/auth_sam.c:logon_hours_ok(120)
>  logon_hours_ok: user asalaices allowed to logon at this time (Thu Feb 14 01:59:22 2008
>  )
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 3] passdb/lookup_sid.c:store_gid_sid_cache(1133)
>  store_gid_sid_cache: gid 513 in cache -> S-1-5-21-2953885461-2572836322-187695268-513
>[2008/02/13 19:59:22, 5] auth/auth_util.c:make_server_info_sam(623)
>  make_server_info_sam: made server info for user asalaices -> asalaices
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] auth/auth.c:check_ntlm_password(270)
>  check_ntlm_password: sam authentication for user [asalaices] succeeded
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] auth/auth.c:check_ntlm_password(296)
>  check_ntlm_password:  PAM Account for user [asalaices] succeeded
>[2008/02/13 19:59:22, 2] auth/auth.c:check_ntlm_password(309)
>  check_ntlm_password:  authentication for user [asalaices] -> [asalaices] -> [asalaices] succeeded
>[2008/02/13 19:59:22, 5] auth/auth_util.c:free_user_info(2045)
>  attempting to free (and zero) a user_info structure
>[2008/02/13 19:59:22, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089)
>  fetch gid from cache 544 -> S-1-5-32-544
>[2008/02/13 19:59:22, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089)
>  fetch gid from cache 10000 -> S-1-5-32-545
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(|(objectclass=sambaGroupMapping)(sambaGroupType=4))(|(sambaSIDList=S-1-5-21-2953885461-2572836322-187695268-21000)(sambaSIDList=S-1-5-21-2953885461-2572836322-187695268-513)(sambaSIDList=S-1-1-0)(sambaSIDList=S-1-5-2)(sambaSIDList=S-1-5-11)(sambaSIDList=S-1-22-2-513)))], scope => [2]
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(|(objectclass=sambaGroupMapping)(sambaGroupType=4))(|(sambaSIDList=S-1-5-21-2953885461-2572836322-187695268-21000)(sambaSIDList=S-1-5-21-2953885461-2572836322-187695268-513)(sambaSIDList=S-1-1-0)(sambaSIDList=S-1-5-2)(sambaSIDList=S-1-5-11)(sambaSIDList=S-1-22-2-513)))], scope => [2]
>[2008/02/13 19:59:22, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2953885461-2572836322-187695268-21000]
>[2008/02/13 19:59:22, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2953885461-2572836322-187695268-513]
>[2008/02/13 19:59:22, 5] lib/privileges.c:get_privileges_for_sids(460)
>  get_privileges_for_sids: sid = S-1-1-0
>  Privilege set:
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2008/02/13 19:59:22, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2008/02/13 19:59:22, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-11]
>[2008/02/13 19:59:22, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-22-2-513]
>[2008/02/13 19:59:22, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-32-545]
>[2008/02/13 19:59:22, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089)
>  fetch gid from cache 513 -> S-1-5-21-2953885461-2572836322-187695268-513
>[2008/02/13 19:59:22, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089)
>  fetch gid from cache 10000 -> S-1-5-32-545
>[2008/02/13 19:59:22, 3] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(338)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2008/02/13 19:59:22, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(63)
>  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_128
>    NTLMSSP_NEGOTIATE_KEY_EXCH
>    NTLMSSP_NEGOTIATE_56
>[2008/02/13 19:59:22, 3] smbd/password.c:register_vuid(280)
>  User name: asalaices	Real name: Jorge Adrian Salaices
>[2008/02/13 19:59:22, 3] smbd/password.c:register_vuid(301)
>  UNIX uid 10000 is UNIX user asalaices, and will be vuid 103
>[2008/02/13 19:59:22, 3] smbd/password.c:register_vuid(332)
>  Adding homes service for user 'asalaices' using home directory: '/home/asalaices'
>[2008/02/13 19:59:22, 3] param/loadparm.c:lp_add_home(2663)
>  adding home's share [asalaices] for user 'asalaices' at '/home/asalaices'
>[2008/02/13 19:59:22, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=132
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=103
>  smb_mid=384
>  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=89
>[2008/02/13 19:59:22, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x5e
>[2008/02/13 19:59:22, 3] smbd/process.c:process_smb(1068)
>  Transaction 8 of length 98
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=94
>  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=103
>  smb_mid=448
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=   94 (0x5E)
>  smb_vwv[ 2]=    8 (0x8)
>  smb_vwv[ 3]=    1 (0x1)
>  smb_bcc=51
>[2008/02/13 19:59:22, 3] smbd/process.c:switch_message(926)
>  switch message SMBtconX (pid 23620) conn 0x0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:22, 4] smbd/reply.c:reply_tcon_and_X(506)
>  Client requested device type [?????] for share [SHOPTEST]
>[2008/02/13 19:59:22, 5] smbd/service.c:make_connection(1205)
>  making a connection to 'normal' service shoptest
>[2008/02/13 19:59:22, 3] lib/util_sid.c:string_to_sid(223)
>  string_to_sid: Sid @domain admins does not start with 'S-'.
>[2008/02/13 19:59:22, 5] smbd/password.c:user_in_netgroup(446)
>  Unable to get default yp domain, let's try without specifying it
>[2008/02/13 19:59:22, 5] smbd/password.c:user_in_netgroup(450)
>  looking for user asalaices of domain (ANY) in netgroup domain admins
>[2008/02/13 19:59:22, 5] smbd/password.c:user_in_netgroup(466)
>  looking for user asalaices of domain (ANY) in netgroup domain admins
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(objectClass=sambaGroupMapping)(|(displayName=domain admins)(cn=domain admins)))], scope => [2]
>[2008/02/13 19:59:22, 2] passdb/pdb_ldap.c:init_group_from_ldap(2158)
>  init_group_from_ldap: Entry found for group: 512
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] lib/util_sid.c:string_to_sid(223)
>  string_to_sid: Sid @shop does not start with 'S-'.
>[2008/02/13 19:59:22, 5] smbd/password.c:user_in_netgroup(446)
>  Unable to get default yp domain, let's try without specifying it
>[2008/02/13 19:59:22, 5] smbd/password.c:user_in_netgroup(450)
>  looking for user asalaices of domain (ANY) in netgroup shop
>[2008/02/13 19:59:22, 5] smbd/password.c:user_in_netgroup(466)
>  looking for user asalaices of domain (ANY) in netgroup shop
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(objectClass=sambaGroupMapping)(|(displayName=shop)(cn=shop)))], scope => [2]
>[2008/02/13 19:59:22, 2] passdb/pdb_ldap.c:init_group_from_ldap(2158)
>  init_group_from_ldap: Entry found for group: 10001
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 2] smbd/service.c:make_connection_snum(616)
>  user 'asalaices' (from session setup) not permitted to access this share (shoptest)
>[2008/02/13 19:59:22, 3] smbd/error.c:error_packet_set(106)
>  error packet at smbd/reply.c(514) cmd=117 (SMBtconX) NT_STATUS_ACCESS_DENIED
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=35
>  smb_com=0x75
>  smb_rcls=34
>  smb_reh=0
>  smb_err=49152
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=103
>  smb_mid=448
>  smt_wct=0
>  smb_bcc=0
>[2008/02/13 19:59:22, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x27
>[2008/02/13 19:59:22, 3] smbd/process.c:process_smb(1068)
>  Transaction 9 of length 43
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=39
>  smb_com=0x74
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=103
>  smb_mid=512
>  smt_wct=2
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_bcc=0
>[2008/02/13 19:59:22, 3] smbd/process.c:switch_message(926)
>  switch message SMBulogoffX (pid 23620) conn 0x0
>[2008/02/13 19:59:22, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:22, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:22, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:22, 3] smbd/reply.c:reply_ulogoffX(1560)
>  ulogoffX vuid=103
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:22, 5] lib/util.c:show_msg(516)
>  size=39
>  smb_com=0x74
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=103
>  smb_mid=512
>  smt_wct=2
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_bcc=0
>[2008/02/13 19:59:33, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x27
>[2008/02/13 19:59:33, 3] smbd/process.c:process_smb(1068)
>  Transaction 10 of length 43
>[2008/02/13 19:59:33, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:33, 5] lib/util.c:show_msg(516)
>  size=39
>  smb_com=0x74
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=576
>  smt_wct=2
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_bcc=0
>[2008/02/13 19:59:33, 3] smbd/process.c:switch_message(926)
>  switch message SMBulogoffX (pid 23620) conn 0x0
>[2008/02/13 19:59:33, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:33, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:33, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:33, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:33, 3] smbd/reply.c:reply_ulogoffX(1560)
>  ulogoffX vuid=101
>[2008/02/13 19:59:33, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:33, 5] lib/util.c:show_msg(516)
>  size=39
>  smb_com=0x74
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=576
>  smt_wct=2
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_bcc=0
>[2008/02/13 19:59:33, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x23
>[2008/02/13 19:59:33, 3] smbd/process.c:process_smb(1068)
>  Transaction 11 of length 39
>[2008/02/13 19:59:33, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:33, 5] lib/util.c:show_msg(516)
>  size=35
>  smb_com=0x71
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=640
>  smt_wct=0
>  smb_bcc=0
>[2008/02/13 19:59:33, 3] smbd/process.c:switch_message(926)
>  switch message SMBtdis (pid 23620) conn 0x916c790
>[2008/02/13 19:59:33, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:33, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:33, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:33, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:33, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:33, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:33, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:33, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:33, 3] smbd/service.c:close_cnum(1230)
>  vwd-df-util1 (10.0.1.22) closed connection to service IPC$
>[2008/02/13 19:59:33, 3] smbd/connection.c:yield_connection(69)
>  Yielding connection to IPC$
>[2008/02/13 19:59:33, 4] smbd/vfs.c:vfs_ChDir(665)
>  vfs_ChDir to /
>[2008/02/13 19:59:33, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:33, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:33, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:33, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:33, 5] lib/util.c:show_msg(506)
>[2008/02/13 19:59:33, 5] lib/util.c:show_msg(516)
>  size=35
>  smb_com=0x71
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=640
>  smt_wct=0
>  smb_bcc=0
>[2008/02/13 19:59:33, 3] smbd/process.c:timeout_processing(1328)
>  timeout_processing: End of file from client (client has disconnected).
>[2008/02/13 19:59:33, 5] lib/gencache.c:gencache_shutdown(94)
>  Closing cache file
>[2008/02/13 19:59:33, 5] libsmb/namecache.c:namecache_shutdown(79)
>  namecache_shutdown: netbios namecache closed successfully.
>[2008/02/13 19:59:33, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 19:59:33, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 19:59:33, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 19:59:33, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 19:59:33, 3] smbd/connection.c:yield_connection(69)
>  Yielding connection to 
>[2008/02/13 19:59:33, 3] smbd/server.c:exit_server_common(768)
>  Server exit (normal exit)
>[2008/02/13 20:00:49, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 20:00:49, 5] smbd/reply.c:reply_special(371)
>  init msg_type=0x81 msg_flags=0x0
>[2008/02/13 20:00:49, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x85
>[2008/02/13 20:00:49, 3] smbd/process.c:process_smb(1068)
>  Transaction 1 of length 137
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=133
>  smb_com=0x72
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51283
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=0
>  smb_mid=0
>  smt_wct=0
>  smb_bcc=98
>[2008/02/13 20:00:49, 3] smbd/process.c:switch_message(926)
>  switch message SMBnegprot (pid 23719) conn 0x0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:00:49, 3] smbd/negprot.c:reply_negprot(505)
>  Requested protocol [PC NETWORK PROGRAM 1.0]
>[2008/02/13 20:00:49, 3] smbd/negprot.c:reply_negprot(505)
>  Requested protocol [LANMAN1.0]
>[2008/02/13 20:00:49, 3] smbd/negprot.c:reply_negprot(505)
>  Requested protocol [Windows for Workgroups 3.1a]
>[2008/02/13 20:00:49, 3] smbd/negprot.c:reply_negprot(505)
>  Requested protocol [LM1.2X002]
>[2008/02/13 20:00:49, 3] smbd/negprot.c:reply_negprot(505)
>  Requested protocol [LANMAN2.1]
>[2008/02/13 20:00:49, 3] smbd/negprot.c:reply_negprot(505)
>  Requested protocol [NT LM 0.12]
>[2008/02/13 20:00:49, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 20:00:49, 5] smbd/connection.c:claim_connection(182)
>  claiming  0
>[2008/02/13 20:00:49, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 20:00:49, 3] smbd/negprot.c:reply_nt1(364)
>  using SPNEGO
>[2008/02/13 20:00:49, 3] smbd/negprot.c:reply_negprot(606)
>  Selected protocol NT LM 0.12
>[2008/02/13 20:00:49, 5] smbd/negprot.c:reply_negprot(612)
>  negprot index=5
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=127
>  smb_com=0x72
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  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]=42752 (0xA700)
>  smb_vwv[ 8]=   92 (0x5C)
>  smb_vwv[ 9]=64768 (0xFD00)
>  smb_vwv[10]=33011 (0x80F3)
>  smb_vwv[11]=32896 (0x8080)
>  smb_vwv[12]=24926 (0x615E)
>  smb_vwv[13]=44395 (0xAD6B)
>  smb_vwv[14]=51310 (0xC86E)
>  smb_vwv[15]=26625 (0x6801)
>  smb_vwv[16]=    1 (0x1)
>  smb_bcc=58
>[2008/02/13 20:00:49, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0xec
>[2008/02/13 20:00:49, 3] smbd/process.c:process_smb(1068)
>  Transaction 2 of length 240
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=236
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=0
>  smb_mid=64
>  smt_wct=12
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=  236 (0xEC)
>  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=177
>[2008/02/13 20:00:49, 3] smbd/process.c:switch_message(926)
>  switch message SMBsesssetupX (pid 23719) conn 0x0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244)
>  wct=12 flg2=0xc807
>[2008/02/13 20:00:49, 2] smbd/sesssetup.c:setup_new_vc_session(1200)
>  setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources.
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1029)
>  Doing spnego session setup
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1060)
>  NativeOS=[Windows 2002 Service Pack 2 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
>[2008/02/13 20:00:49, 5] smbd/sesssetup.c:parse_spnego_mechanisms(667)
>  parse_spnego_mechanisms: Got OID 1 3 6 1 4 1 311 2 2 10
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_spnego_negotiate(697)
>  reply_spnego_negotiate: Got secblob of size 40
>[2008/02/13 20:00:49, 5] auth/auth.c:make_auth_context_subsystem(483)
>  Making default auth method list for DC, security=user, encrypt passwords = yes
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend sam
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'sam'
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend sam_ignoredomain
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'sam_ignoredomain'
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend unix
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'unix'
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend winbind
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'winbind'
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend smbserver
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'smbserver'
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend trustdomain
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'trustdomain'
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend ntdomain
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'ntdomain'
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(47)
>  Attempting to register auth backend guest
>[2008/02/13 20:00:49, 5] auth/auth.c:smb_register_auth(59)
>  Successfully added auth method 'guest'
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match guest
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method guest has a valid init
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match sam
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method sam has a valid init
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match winbind:trustdomain
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match trustdomain
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method trustdomain has a valid init
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method winbind has a valid init
>[2008/02/13 20:00:49, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(63)
>  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_128
>    NTLMSSP_NEGOTIATE_KEY_EXCH
>    NTLMSSP_NEGOTIATE_56
>[2008/02/13 20:00:49, 5] auth/auth.c:get_ntlm_challenge(97)
>  auth_get_challenge: module guest did not want to specify a challenge
>[2008/02/13 20:00:49, 5] auth/auth.c:get_ntlm_challenge(97)
>  auth_get_challenge: module sam did not want to specify a challenge
>[2008/02/13 20:00:49, 5] auth/auth.c:get_ntlm_challenge(97)
>  auth_get_challenge: module winbind did not want to specify a challenge
>[2008/02/13 20:00:49, 5] auth/auth.c:get_ntlm_challenge(137)
>  auth_context challenge created by random
>[2008/02/13 20:00:49, 5] auth/auth.c:get_ntlm_challenge(138)
>  challenge is: 
>[2008/02/13 20:00:49, 5] lib/util.c:dump_data(2283)
>  [000] 4F E7 DA DD A5 34 CA 0F                           O....4.. 
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=344
>  smb_com=0x73
>  smb_rcls=22
>  smb_reh=0
>  smb_err=49152
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=100
>  smb_mid=64
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=  221 (0xDD)
>  smb_bcc=301
>[2008/02/13 20:00:49, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x11a
>[2008/02/13 20:00:49, 3] smbd/process.c:process_smb(1068)
>  Transaction 3 of length 286
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=282
>  smb_com=0x73
>  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=128
>  smt_wct=12
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=  282 (0x11A)
>  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]=  121 (0x79)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_vwv[10]=  212 (0xD4)
>  smb_vwv[11]=40960 (0xA000)
>  smb_bcc=223
>[2008/02/13 20:00:49, 3] smbd/process.c:switch_message(926)
>  switch message SMBsesssetupX (pid 23719) conn 0x0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244)
>  wct=12 flg2=0xc807
>[2008/02/13 20:00:49, 2] smbd/sesssetup.c:setup_new_vc_session(1200)
>  setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources.
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1029)
>  Doing spnego session setup
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1060)
>  NativeOS=[Windows 2002 Service Pack 2 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
>[2008/02/13 20:00:49, 3] libsmb/ntlmssp.c:ntlmssp_server_auth(739)
>  Got user=[] domain=[] workstation=[VWD-DF-UTIL1] len1=1 len2=0
>[2008/02/13 20:00:49, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 20:00:49, 5] auth/auth_util.c:make_user_info_map(161)
>  make_user_info_map: Mapping user []\[] from workstation [VWD-DF-UTIL1]
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] auth/auth_util.c:is_trusted_domain(2198)
>  is_trusted_domain: Checking for domain trust with [AVATAS.COM]
>[2008/02/13 20:00:49, 5] passdb/secrets.c:secrets_fetch_trusted_domain_password(473)
>  secrets_fetch failed!
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] libsmb/trustdom_cache.c:trustdom_cache_fetch(184)
>  no entry for trusted domain AVATAS.COM found.
>[2008/02/13 20:00:49, 5] auth/auth_util.c:make_user_info(75)
>  attempting to make a user_info for  ()
>[2008/02/13 20:00:49, 5] auth/auth_util.c:make_user_info(85)
>  making strings for 's user_info struct
>[2008/02/13 20:00:49, 5] auth/auth_util.c:make_user_info(117)
>  making blobs for 's user_info struct
>[2008/02/13 20:00:49, 3] auth/auth.c:check_ntlm_password(221)
>  check_ntlm_password:  Checking password for unmapped user []\[]@[VWD-DF-UTIL1] with the new password interface
>[2008/02/13 20:00:49, 3] auth/auth.c:check_ntlm_password(224)
>  check_ntlm_password:  mapped user is: [AVATAS.COM]\[]@[VWD-DF-UTIL1]
>[2008/02/13 20:00:49, 5] lib/util.c:dump_data(2283)
>  [000] 4F E7 DA DD A5 34 CA 0F                           O....4.. 
>[2008/02/13 20:00:49, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(objectClass=sambaGroupMapping)(gidNumber=99))], scope => [2]
>[2008/02/13 20:00:49, 5] lib/smbldap.c:smbldap_close(1085)
>  The connection to the LDAP server was closed
>[2008/02/13 20:00:49, 2] lib/smbldap.c:smbldap_open_connection(786)
>  smbldap_open_connection: connection opened
>[2008/02/13 20:00:49, 3] lib/smbldap.c:smbldap_check_root_dse(1694)
>  smbldap_check_root_dse: Expected one rootDSE, got 0
>[2008/02/13 20:00:49, 3] lib/smbldap.c:smbldap_connect_system(997)
>  ldap_connect_system: succesful connection to the LDAP server
>[2008/02/13 20:00:49, 4] lib/smbldap.c:smbldap_open(1065)
>  The LDAP server is succesfully connected
>[2008/02/13 20:00:49, 4] passdb/pdb_ldap.c:ldapsam_getgroup(2235)
>  ldapsam_getgroup: Did not find group
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 4] lib/substitute.c:automount_server(407)
>  Home server: lsv-df-dc1
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] auth/auth.c:check_ntlm_password(270)
>  check_ntlm_password: guest authentication for user [] succeeded
>[2008/02/13 20:00:49, 5] auth/auth.c:check_ntlm_password(309)
>  check_ntlm_password:  guest authentication for user [] -> [] -> [nobody] succeeded
>[2008/02/13 20:00:49, 5] auth/auth_util.c:free_user_info(2045)
>  attempting to free (and zero) a user_info structure
>[2008/02/13 20:00:49, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089)
>  fetch gid from cache 544 -> S-1-5-32-544
>[2008/02/13 20:00:49, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089)
>  fetch gid from cache 10000 -> S-1-5-32-545
>[2008/02/13 20:00:49, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(|(objectclass=sambaGroupMapping)(sambaGroupType=4))(|(sambaSIDList=S-1-5-21-2953885461-2572836322-187695268-501)(sambaSIDList=S-1-1-0)(sambaSIDList=S-1-5-2)(sambaSIDList=S-1-5-32-546)))], scope => [2]
>[2008/02/13 20:00:49, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(|(objectclass=sambaGroupMapping)(sambaGroupType=4))(|(sambaSIDList=S-1-5-21-2953885461-2572836322-187695268-501)(sambaSIDList=S-1-1-0)(sambaSIDList=S-1-5-2)(sambaSIDList=S-1-5-32-546)))], scope => [2]
>[2008/02/13 20:00:49, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2953885461-2572836322-187695268-501]
>[2008/02/13 20:00:49, 5] lib/privileges.c:get_privileges_for_sids(460)
>  get_privileges_for_sids: sid = S-1-1-0
>  Privilege set:
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2008/02/13 20:00:49, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2008/02/13 20:00:49, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-32-546]
>[2008/02/13 20:00:49, 3] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(338)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2008/02/13 20:00:49, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(63)
>  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_128
>    NTLMSSP_NEGOTIATE_KEY_EXCH
>    NTLMSSP_NEGOTIATE_56
>[2008/02/13 20:00:49, 3] smbd/password.c:register_vuid(280)
>  User name: nobody	Real name: Nobody
>[2008/02/13 20:00:49, 3] smbd/password.c:register_vuid(301)
>  UNIX uid 99 is UNIX user nobody, and will be vuid 101
>[2008/02/13 20:00:49, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=132
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=128
>  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=89
>[2008/02/13 20:00:49, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x56
>[2008/02/13 20:00:49, 3] smbd/process.c:process_smb(1068)
>  Transaction 4 of length 90
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=86
>  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=101
>  smb_mid=192
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=   86 (0x56)
>  smb_vwv[ 2]=    8 (0x8)
>  smb_vwv[ 3]=    1 (0x1)
>  smb_bcc=43
>[2008/02/13 20:00:49, 3] smbd/process.c:switch_message(926)
>  switch message SMBtconX (pid 23719) conn 0x0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:00:49, 4] smbd/reply.c:reply_tcon_and_X(506)
>  Client requested device type [?????] for share [IPC$]
>[2008/02/13 20:00:49, 5] smbd/service.c:make_connection(1205)
>  making a connection to 'normal' service ipc$
>[2008/02/13 20:00:49, 5] lib/username.c:Get_Pwnam_alloc(131)
>  Finding user nobody
>[2008/02/13 20:00:49, 5] lib/username.c:Get_Pwnam_internals(75)
>  Trying _Get_Pwnam(), username as lowercase is nobody
>[2008/02/13 20:00:49, 5] lib/username.c:Get_Pwnam_internals(108)
>  Get_Pwnam_internals did find user [nobody]!
>[2008/02/13 20:00:49, 3] smbd/service.c:make_connection_snum(806)
>  Connect path is '/tmp' for service [IPC$]
>[2008/02/13 20:00:49, 4] lib/sharesec.c:get_share_security(132)
>  get_share_security: using default secdesc for IPC$
>[2008/02/13 20:00:49, 3] lib/util_seaccess.c:se_access_check(250)
>[2008/02/13 20:00:49, 3] lib/util_seaccess.c:se_access_check(251)
>  se_access_check: user sid is S-1-5-21-2953885461-2572836322-187695268-501
>  se_access_check: also S-1-1-0
>  se_access_check: also S-1-5-2
>  se_access_check: also S-1-5-32-546
>[2008/02/13 20:00:49, 5] lib/util_seaccess.c:se_access_check(308)
>  se_access_check: access (2) granted.
>[2008/02/13 20:00:49, 3] smbd/vfs.c:vfs_init_default(95)
>  Initialising default vfs hooks
>[2008/02/13 20:00:49, 5] smbd/vfs.c:smb_register_vfs(85)
>  Successfully added vfs backend '/[Default VFS]/'
>[2008/02/13 20:00:49, 5] smbd/vfs.c:smb_register_vfs(85)
>  Successfully added vfs backend 'posixacl'
>[2008/02/13 20:00:49, 3] smbd/vfs.c:vfs_init_custom(128)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>  Successfully loaded vfs module [/[Default VFS]/] with the new modules system
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #0 (type 0, layer 0)
>  Making operation type 0 opaque [module /[Default VFS]/]
>  Accepting operation type 0 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #1 (type 1, layer 0)
>  Making operation type 1 opaque [module /[Default VFS]/]
>  Accepting operation type 1 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #2 (type 2, layer 0)
>  Making operation type 2 opaque [module /[Default VFS]/]
>  Accepting operation type 2 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #3 (type 3, layer 0)
>  Making operation type 3 opaque [module /[Default VFS]/]
>  Accepting operation type 3 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #4 (type 4, layer 0)
>  Making operation type 4 opaque [module /[Default VFS]/]
>  Accepting operation type 4 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #5 (type 5, layer 0)
>  Making operation type 5 opaque [module /[Default VFS]/]
>  Accepting operation type 5 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #6 (type 6, layer 0)
>  Making operation type 6 opaque [module /[Default VFS]/]
>  Accepting operation type 6 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #7 (type 7, layer 0)
>  Making operation type 7 opaque [module /[Default VFS]/]
>  Accepting operation type 7 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #8 (type 8, layer 0)
>  Making operation type 8 opaque [module /[Default VFS]/]
>  Accepting operation type 8 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #9 (type 9, layer 0)
>  Making operation type 9 opaque [module /[Default VFS]/]
>  Accepting operation type 9 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #10 (type 10, layer 0)
>  Making operation type 10 opaque [module /[Default VFS]/]
>  Accepting operation type 10 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #11 (type 11, layer 0)
>  Making operation type 11 opaque [module /[Default VFS]/]
>  Accepting operation type 11 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #12 (type 12, layer 0)
>  Making operation type 12 opaque [module /[Default VFS]/]
>  Accepting operation type 12 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #13 (type 13, layer 0)
>  Making operation type 13 opaque [module /[Default VFS]/]
>  Accepting operation type 13 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #14 (type 14, layer 0)
>  Making operation type 14 opaque [module /[Default VFS]/]
>  Accepting operation type 14 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #15 (type 15, layer 0)
>  Making operation type 15 opaque [module /[Default VFS]/]
>  Accepting operation type 15 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #16 (type 16, layer 0)
>  Making operation type 16 opaque [module /[Default VFS]/]
>  Accepting operation type 16 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #17 (type 17, layer 0)
>  Making operation type 17 opaque [module /[Default VFS]/]
>  Accepting operation type 17 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #18 (type 18, layer 0)
>  Making operation type 18 opaque [module /[Default VFS]/]
>  Accepting operation type 18 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #19 (type 19, layer 0)
>  Making operation type 19 opaque [module /[Default VFS]/]
>  Accepting operation type 19 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #20 (type 20, layer 0)
>  Making operation type 20 opaque [module /[Default VFS]/]
>  Accepting operation type 20 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #21 (type 21, layer 0)
>  Making operation type 21 opaque [module /[Default VFS]/]
>  Accepting operation type 21 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #22 (type 22, layer 0)
>  Making operation type 22 opaque [module /[Default VFS]/]
>  Accepting operation type 22 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #23 (type 23, layer 0)
>  Making operation type 23 opaque [module /[Default VFS]/]
>  Accepting operation type 23 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #24 (type 24, layer 0)
>  Making operation type 24 opaque [module /[Default VFS]/]
>  Accepting operation type 24 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #25 (type 25, layer 0)
>  Making operation type 25 opaque [module /[Default VFS]/]
>  Accepting operation type 25 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #26 (type 26, layer 0)
>  Making operation type 26 opaque [module /[Default VFS]/]
>  Accepting operation type 26 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #27 (type 27, layer 0)
>  Making operation type 27 opaque [module /[Default VFS]/]
>  Accepting operation type 27 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #28 (type 28, layer 0)
>  Making operation type 28 opaque [module /[Default VFS]/]
>  Accepting operation type 28 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #29 (type 29, layer 0)
>  Making operation type 29 opaque [module /[Default VFS]/]
>  Accepting operation type 29 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #30 (type 30, layer 0)
>  Making operation type 30 opaque [module /[Default VFS]/]
>  Accepting operation type 30 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #31 (type 31, layer 0)
>  Making operation type 31 opaque [module /[Default VFS]/]
>  Accepting operation type 31 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #32 (type 32, layer 0)
>  Making operation type 32 opaque [module /[Default VFS]/]
>  Accepting operation type 32 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #33 (type 33, layer 0)
>  Making operation type 33 opaque [module /[Default VFS]/]
>  Accepting operation type 33 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #34 (type 34, layer 0)
>  Making operation type 34 opaque [module /[Default VFS]/]
>  Accepting operation type 34 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #35 (type 35, layer 0)
>  Making operation type 35 opaque [module /[Default VFS]/]
>  Accepting operation type 35 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #36 (type 36, layer 0)
>  Making operation type 36 opaque [module /[Default VFS]/]
>  Accepting operation type 36 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #37 (type 37, layer 0)
>  Making operation type 37 opaque [module /[Default VFS]/]
>  Accepting operation type 37 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #38 (type 38, layer 0)
>  Making operation type 38 opaque [module /[Default VFS]/]
>  Accepting operation type 38 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #39 (type 39, layer 0)
>  Making operation type 39 opaque [module /[Default VFS]/]
>  Accepting operation type 39 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #40 (type 40, layer 0)
>  Making operation type 40 opaque [module /[Default VFS]/]
>  Accepting operation type 40 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #41 (type 41, layer 0)
>  Making operation type 41 opaque [module /[Default VFS]/]
>  Accepting operation type 41 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #42 (type 42, layer 0)
>  Making operation type 42 opaque [module /[Default VFS]/]
>  Accepting operation type 42 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #43 (type 43, layer 0)
>  Making operation type 43 opaque [module /[Default VFS]/]
>  Accepting operation type 43 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #44 (type 44, layer 0)
>  Making operation type 44 opaque [module /[Default VFS]/]
>  Accepting operation type 44 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #45 (type 45, layer 0)
>  Making operation type 45 opaque [module /[Default VFS]/]
>  Accepting operation type 45 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #46 (type 46, layer 0)
>  Making operation type 46 opaque [module /[Default VFS]/]
>  Accepting operation type 46 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #47 (type 47, layer 0)
>  Making operation type 47 opaque [module /[Default VFS]/]
>  Accepting operation type 47 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #48 (type 48, layer 0)
>  Making operation type 48 opaque [module /[Default VFS]/]
>  Accepting operation type 48 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #49 (type 49, layer 0)
>  Making operation type 49 opaque [module /[Default VFS]/]
>  Accepting operation type 49 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #50 (type 50, layer 0)
>  Making operation type 50 opaque [module /[Default VFS]/]
>  Accepting operation type 50 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #51 (type 51, layer 0)
>  Making operation type 51 opaque [module /[Default VFS]/]
>  Accepting operation type 51 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #52 (type 52, layer 0)
>  Making operation type 52 opaque [module /[Default VFS]/]
>  Accepting operation type 52 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #53 (type 53, layer 0)
>  Making operation type 53 opaque [module /[Default VFS]/]
>  Accepting operation type 53 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #54 (type 54, layer 0)
>  Making operation type 54 opaque [module /[Default VFS]/]
>  Accepting operation type 54 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #55 (type 55, layer 0)
>  Making operation type 55 opaque [module /[Default VFS]/]
>  Accepting operation type 55 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #56 (type 56, layer 0)
>  Making operation type 56 opaque [module /[Default VFS]/]
>  Accepting operation type 56 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #57 (type 57, layer 0)
>  Making operation type 57 opaque [module /[Default VFS]/]
>  Accepting operation type 57 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #58 (type 58, layer 0)
>  Making operation type 58 opaque [module /[Default VFS]/]
>  Accepting operation type 58 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #59 (type 59, layer 0)
>  Making operation type 59 opaque [module /[Default VFS]/]
>  Accepting operation type 59 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #60 (type 60, layer 0)
>  Making operation type 60 opaque [module /[Default VFS]/]
>  Accepting operation type 60 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #61 (type 61, layer 0)
>  Making operation type 61 opaque [module /[Default VFS]/]
>  Accepting operation type 61 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #62 (type 62, layer 0)
>  Making operation type 62 opaque [module /[Default VFS]/]
>  Accepting operation type 62 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #63 (type 63, layer 0)
>  Making operation type 63 opaque [module /[Default VFS]/]
>  Accepting operation type 63 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #64 (type 64, layer 0)
>  Making operation type 64 opaque [module /[Default VFS]/]
>  Accepting operation type 64 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #65 (type 65, layer 0)
>  Making operation type 65 opaque [module /[Default VFS]/]
>  Accepting operation type 65 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #66 (type 66, layer 0)
>  Making operation type 66 opaque [module /[Default VFS]/]
>  Accepting operation type 66 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #67 (type 67, layer 0)
>  Making operation type 67 opaque [module /[Default VFS]/]
>  Accepting operation type 67 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #68 (type 68, layer 0)
>  Making operation type 68 opaque [module /[Default VFS]/]
>  Accepting operation type 68 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #69 (type 69, layer 0)
>  Making operation type 69 opaque [module /[Default VFS]/]
>  Accepting operation type 69 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #70 (type 70, layer 0)
>  Making operation type 70 opaque [module /[Default VFS]/]
>  Accepting operation type 70 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #71 (type 71, layer 0)
>  Making operation type 71 opaque [module /[Default VFS]/]
>  Accepting operation type 71 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #72 (type 72, layer 0)
>  Making operation type 72 opaque [module /[Default VFS]/]
>  Accepting operation type 72 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #73 (type 73, layer 0)
>  Making operation type 73 opaque [module /[Default VFS]/]
>  Accepting operation type 73 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #74 (type 74, layer 0)
>  Making operation type 74 opaque [module /[Default VFS]/]
>  Accepting operation type 74 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #75 (type 75, layer 0)
>  Making operation type 75 opaque [module /[Default VFS]/]
>  Accepting operation type 75 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #76 (type 76, layer 0)
>  Making operation type 76 opaque [module /[Default VFS]/]
>  Accepting operation type 76 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #77 (type 77, layer 0)
>  Making operation type 77 opaque [module /[Default VFS]/]
>  Accepting operation type 77 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #78 (type 78, layer 0)
>  Making operation type 78 opaque [module /[Default VFS]/]
>  Accepting operation type 78 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #79 (type 79, layer 0)
>  Making operation type 79 opaque [module /[Default VFS]/]
>  Accepting operation type 79 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #80 (type 80, layer 0)
>  Making operation type 80 opaque [module /[Default VFS]/]
>  Accepting operation type 80 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #81 (type 81, layer 0)
>  Making operation type 81 opaque [module /[Default VFS]/]
>  Accepting operation type 81 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #82 (type 82, layer 0)
>  Making operation type 82 opaque [module /[Default VFS]/]
>  Accepting operation type 82 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #83 (type 83, layer 0)
>  Making operation type 83 opaque [module /[Default VFS]/]
>  Accepting operation type 83 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #84 (type 84, layer 0)
>  Making operation type 84 opaque [module /[Default VFS]/]
>  Accepting operation type 84 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #85 (type 85, layer 0)
>  Making operation type 85 opaque [module /[Default VFS]/]
>  Accepting operation type 85 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #86 (type 86, layer 0)
>  Making operation type 86 opaque [module /[Default VFS]/]
>  Accepting operation type 86 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #87 (type 87, layer 0)
>  Making operation type 87 opaque [module /[Default VFS]/]
>  Accepting operation type 87 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #88 (type 88, layer 0)
>  Making operation type 88 opaque [module /[Default VFS]/]
>  Accepting operation type 88 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #89 (type 89, layer 0)
>  Making operation type 89 opaque [module /[Default VFS]/]
>  Accepting operation type 89 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #90 (type 90, layer 0)
>  Making operation type 90 opaque [module /[Default VFS]/]
>  Accepting operation type 90 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #91 (type 91, layer 0)
>  Making operation type 91 opaque [module /[Default VFS]/]
>  Accepting operation type 91 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #92 (type 92, layer 0)
>  Making operation type 92 opaque [module /[Default VFS]/]
>  Accepting operation type 92 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #93 (type 93, layer 0)
>  Making operation type 93 opaque [module /[Default VFS]/]
>  Accepting operation type 93 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/vfs.c:vfs_init_custom(174)
>  Checking operation #94 (type 94, layer 0)
>  Making operation type 94 opaque [module /[Default VFS]/]
>  Accepting operation type 94 from module /[Default VFS]/
>[2008/02/13 20:00:49, 5] smbd/connection.c:claim_connection(182)
>  claiming IPC$ 0
>[2008/02/13 20:00:49, 4] lib/sharesec.c:get_share_security(132)
>  get_share_security: using default secdesc for IPC$
>[2008/02/13 20:00:49, 3] lib/util_seaccess.c:se_access_check(250)
>[2008/02/13 20:00:49, 3] lib/util_seaccess.c:se_access_check(251)
>  se_access_check: user sid is S-1-5-21-2953885461-2572836322-187695268-501
>  se_access_check: also S-1-1-0
>  se_access_check: also S-1-5-2
>  se_access_check: also S-1-5-32-546
>[2008/02/13 20:00:49, 5] lib/util_seaccess.c:se_access_check(308)
>  se_access_check: access (1) granted.
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (99, 99) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(454)
>  NT user token of user S-1-5-21-2953885461-2572836322-187695268-501
>  contains 4 SIDs
>  SID[  0]: S-1-5-21-2953885461-2572836322-187695268-501
>  SID[  1]: S-1-1-0
>  SID[  2]: S-1-5-2
>  SID[  3]: S-1-5-32-546
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 99
>  Primary group is 99 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] smbd/uid.c:change_to_user(273)
>  change_to_user uid=(99,99) gid=(0,99)
>[2008/02/13 20:00:49, 3] smbd/service.c:make_connection_snum(1033)
>  vwd-df-util1 (10.0.1.22) connect to service IPC$ initially as user nobody (uid=99, gid=99) (pid 23719)
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:00:49, 3] smbd/reply.c:reply_tcon_and_X(574)
>  tconX service=IPC$ 
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=56
>  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=101
>  smb_mid=192
>  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
>[2008/02/13 20:00:49, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x70
>[2008/02/13 20:00:49, 3] smbd/process.c:process_smb(1068)
>  Transaction 5 of length 116
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=112
>  smb_com=0x32
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=4
>  smb_uid=101
>  smb_mid=256
>  smt_wct=15
>  smb_vwv[ 0]=   44 (0x2C)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]= 4096 (0x1000)
>  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]=   44 (0x2C)
>  smb_vwv[10]=   68 (0x44)
>  smb_vwv[11]=    0 (0x0)
>  smb_vwv[12]=    0 (0x0)
>  smb_vwv[13]=    1 (0x1)
>  smb_vwv[14]=   16 (0x10)
>  smb_bcc=47
>[2008/02/13 20:00:49, 3] smbd/process.c:switch_message(926)
>  switch message SMBtrans2 (pid 23719) conn 0x916cf18
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (99, 99) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(454)
>  NT user token of user S-1-5-21-2953885461-2572836322-187695268-501
>  contains 4 SIDs
>  SID[  0]: S-1-5-21-2953885461-2572836322-187695268-501
>  SID[  1]: S-1-1-0
>  SID[  2]: S-1-5-2
>  SID[  3]: S-1-5-32-546
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 99
>  Primary group is 99 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] smbd/uid.c:change_to_user(273)
>  change_to_user uid=(99,99) gid=(0,99)
>[2008/02/13 20:00:49, 4] smbd/vfs.c:vfs_ChDir(665)
>  vfs_ChDir to /tmp
>[2008/02/13 20:00:49, 3] smbd/msdfs.c:get_referred_path(624)
>  get_referred_path: |shoptest| in dfs path \lsv-df-dc1\shoptest is not a dfs root.
>[2008/02/13 20:00:49, 3] smbd/error.c:error_packet_set(106)
>  error packet at smbd/trans2.c(6205) cmd=50 (SMBtrans2) NT_STATUS_NOT_FOUND
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=35
>  smb_com=0x32
>  smb_rcls=37
>  smb_reh=2
>  smb_err=49152
>  smb_flg=136
>  smb_flg2=51265
>  smb_tid=1
>  smb_pid=4
>  smb_uid=101
>  smb_mid=256
>  smt_wct=0
>  smb_bcc=0
>[2008/02/13 20:00:49, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0xec
>[2008/02/13 20:00:49, 3] smbd/process.c:process_smb(1068)
>  Transaction 6 of length 240
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=236
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=0
>  smb_mid=320
>  smt_wct=12
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=  236 (0xEC)
>  smb_vwv[ 2]=16644 (0x4104)
>  smb_vwv[ 3]=   50 (0x32)
>  smb_vwv[ 4]=    1 (0x1)
>  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=177
>[2008/02/13 20:00:49, 3] smbd/process.c:switch_message(926)
>  switch message SMBsesssetupX (pid 23719) conn 0x0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244)
>  wct=12 flg2=0xc807
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1029)
>  Doing spnego session setup
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1060)
>  NativeOS=[Windows 2002 Service Pack 2 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
>[2008/02/13 20:00:49, 5] smbd/sesssetup.c:parse_spnego_mechanisms(667)
>  parse_spnego_mechanisms: Got OID 1 3 6 1 4 1 311 2 2 10
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_spnego_negotiate(697)
>  reply_spnego_negotiate: Got secblob of size 40
>[2008/02/13 20:00:49, 5] auth/auth.c:make_auth_context_subsystem(483)
>  Making default auth method list for DC, security=user, encrypt passwords = yes
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match guest
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method guest has a valid init
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match sam
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method sam has a valid init
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match winbind:trustdomain
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(391)
>  load_auth_module: Attempting to find an auth method to match trustdomain
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method trustdomain has a valid init
>[2008/02/13 20:00:49, 5] auth/auth.c:load_auth_module(416)
>  load_auth_module: auth method winbind has a valid init
>[2008/02/13 20:00:49, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(63)
>  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_128
>    NTLMSSP_NEGOTIATE_KEY_EXCH
>    NTLMSSP_NEGOTIATE_56
>[2008/02/13 20:00:49, 5] auth/auth.c:get_ntlm_challenge(97)
>  auth_get_challenge: module guest did not want to specify a challenge
>[2008/02/13 20:00:49, 5] auth/auth.c:get_ntlm_challenge(97)
>  auth_get_challenge: module sam did not want to specify a challenge
>[2008/02/13 20:00:49, 5] auth/auth.c:get_ntlm_challenge(97)
>  auth_get_challenge: module winbind did not want to specify a challenge
>[2008/02/13 20:00:49, 5] auth/auth.c:get_ntlm_challenge(137)
>  auth_context challenge created by random
>[2008/02/13 20:00:49, 5] auth/auth.c:get_ntlm_challenge(138)
>  challenge is: 
>[2008/02/13 20:00:49, 5] lib/util.c:dump_data(2283)
>  [000] 6F 92 51 C6 B9 31 8C 90                           o.Q..1.. 
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=344
>  smb_com=0x73
>  smb_rcls=22
>  smb_reh=0
>  smb_err=49152
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=102
>  smb_mid=320
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_vwv[ 2]=    0 (0x0)
>  smb_vwv[ 3]=  221 (0xDD)
>  smb_bcc=301
>[2008/02/13 20:00:49, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x174
>[2008/02/13 20:00:49, 3] smbd/process.c:process_smb(1068)
>  Transaction 7 of length 376
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=372
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=102
>  smb_mid=384
>  smt_wct=12
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=  372 (0x174)
>  smb_vwv[ 2]=16644 (0x4104)
>  smb_vwv[ 3]=   50 (0x32)
>  smb_vwv[ 4]=    1 (0x1)
>  smb_vwv[ 5]=    0 (0x0)
>  smb_vwv[ 6]=    0 (0x0)
>  smb_vwv[ 7]=  210 (0xD2)
>  smb_vwv[ 8]=    0 (0x0)
>  smb_vwv[ 9]=    0 (0x0)
>  smb_vwv[10]=  212 (0xD4)
>  smb_vwv[11]=40960 (0xA000)
>  smb_bcc=313
>[2008/02/13 20:00:49, 3] smbd/process.c:switch_message(926)
>  switch message SMBsesssetupX (pid 23719) conn 0x0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244)
>  wct=12 flg2=0xc807
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1029)
>  Doing spnego session setup
>[2008/02/13 20:00:49, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1060)
>  NativeOS=[Windows 2002 Service Pack 2 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
>[2008/02/13 20:00:49, 3] libsmb/ntlmssp.c:ntlmssp_server_auth(739)
>  Got user=[asalaices] domain=[avatas.com] workstation=[VWD-DF-UTIL1] len1=24 len2=24
>[2008/02/13 20:00:49, 5] auth/auth_ntlmssp.c:auth_ntlmssp_set_challenge(69)
>  auth_context challenge set by NTLMSSP callback (NTLM2)
>[2008/02/13 20:00:49, 5] auth/auth_ntlmssp.c:auth_ntlmssp_set_challenge(70)
>  challenge is: 
>[2008/02/13 20:00:49, 5] lib/util.c:dump_data(2283)
>  [000] 40 2A B3 DA FB A6 EA 07                           @*...... 
>[2008/02/13 20:00:49, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 20:00:49, 4] smbd/map_username.c:map_username(111)
>  Scanning username map /etc/samba/smbusers
>[2008/02/13 20:00:49, 5] auth/auth_util.c:make_user_info_map(161)
>  make_user_info_map: Mapping user [avatas.com]\[asalaices] from workstation [VWD-DF-UTIL1]
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] auth/auth_util.c:is_trusted_domain(2198)
>  is_trusted_domain: Checking for domain trust with [avatas.com]
>[2008/02/13 20:00:49, 5] passdb/secrets.c:secrets_fetch_trusted_domain_password(473)
>  secrets_fetch failed!
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] libsmb/trustdom_cache.c:trustdom_cache_fetch(184)
>  no entry for trusted domain avatas.com found.
>[2008/02/13 20:00:49, 5] auth/auth_util.c:make_user_info(75)
>  attempting to make a user_info for asalaices (asalaices)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:make_user_info(85)
>  making strings for asalaices's user_info struct
>[2008/02/13 20:00:49, 5] auth/auth_util.c:make_user_info(117)
>  making blobs for asalaices's user_info struct
>[2008/02/13 20:00:49, 3] auth/auth.c:check_ntlm_password(221)
>  check_ntlm_password:  Checking password for unmapped user [avatas.com]\[asalaices]@[VWD-DF-UTIL1] with the new password interface
>[2008/02/13 20:00:49, 3] auth/auth.c:check_ntlm_password(224)
>  check_ntlm_password:  mapped user is: [avatas.com]\[asalaices]@[VWD-DF-UTIL1]
>[2008/02/13 20:00:49, 5] lib/util.c:dump_data(2283)
>  [000] 40 2A B3 DA FB A6 EA 07                           @*...... 
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [dc=avatas,dc=com], filter => [(&(uid=asalaices)(objectclass=sambaSamAccount))], scope => [2]
>[2008/02/13 20:00:49, 2] passdb/pdb_ldap.c:init_sam_from_ldap(545)
>  init_sam_from_ldap: Entry found for user: asalaices
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 5] passdb/login_cache.c:login_cache_init(41)
>  Opening cache file at /var/cache/samba/login_cache.tdb
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 5] lib/username.c:Get_Pwnam_alloc(131)
>  Finding user asalaices
>[2008/02/13 20:00:49, 5] lib/username.c:Get_Pwnam_internals(75)
>  Trying _Get_Pwnam(), username as lowercase is asalaices
>[2008/02/13 20:00:49, 5] lib/username.c:Get_Pwnam_internals(108)
>  Get_Pwnam_internals did find user [asalaices]!
>[2008/02/13 20:00:49, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(objectClass=sambaGroupMapping)(gidNumber=513))], scope => [2]
>[2008/02/13 20:00:49, 2] passdb/pdb_ldap.c:init_group_from_ldap(2158)
>  init_group_from_ldap: Entry found for group: 513
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] passdb/pdb_interface.c:lookup_global_sam_rid(1522)
>  lookup_global_sam_rid: looking up RID 513.
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [dc=avatas,dc=com], filter => [(&(sambaSID=S-1-5-21-2953885461-2572836322-187695268-513)(objectclass=sambaSamAccount))], scope => [2]
>[2008/02/13 20:00:49, 4] passdb/pdb_ldap.c:ldapsam_getsampwsid(1496)
>  ldapsam_getsampwsid: Unable to locate SID [S-1-5-21-2953885461-2572836322-187695268-513] count=0
>[2008/02/13 20:00:49, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(objectClass=sambaGroupMapping)(sambaSID=S-1-5-21-2953885461-2572836322-187695268-513))], scope => [2]
>[2008/02/13 20:00:49, 2] passdb/pdb_ldap.c:init_group_from_ldap(2158)
>  init_group_from_ldap: Entry found for group: 513
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2008/02/13 20:00:49, 5] passdb/pdb_interface.c:pdb_default_lookup_rids(1643)
>  lookup_rids: Domain Users:2
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 4] libsmb/ntlm_check.c:ntlm_password_check(326)
>  ntlm_password_check: Checking NT MD4 password
>[2008/02/13 20:00:49, 4] auth/auth_sam.c:sam_account_ok(138)
>  sam_account_ok: Checking SMB password for user asalaices
>[2008/02/13 20:00:49, 5] auth/auth_sam.c:logon_hours_ok(120)
>  logon_hours_ok: user asalaices allowed to logon at this time (Thu Feb 14 02:00:49 2008
>  )
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 3] passdb/lookup_sid.c:store_gid_sid_cache(1133)
>  store_gid_sid_cache: gid 513 in cache -> S-1-5-21-2953885461-2572836322-187695268-513
>[2008/02/13 20:00:49, 5] auth/auth_util.c:make_server_info_sam(623)
>  make_server_info_sam: made server info for user asalaices -> asalaices
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] auth/auth.c:check_ntlm_password(270)
>  check_ntlm_password: sam authentication for user [asalaices] succeeded
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] auth/auth.c:check_ntlm_password(296)
>  check_ntlm_password:  PAM Account for user [asalaices] succeeded
>[2008/02/13 20:00:49, 2] auth/auth.c:check_ntlm_password(309)
>  check_ntlm_password:  authentication for user [asalaices] -> [asalaices] -> [asalaices] succeeded
>[2008/02/13 20:00:49, 5] auth/auth_util.c:free_user_info(2045)
>  attempting to free (and zero) a user_info structure
>[2008/02/13 20:00:49, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089)
>  fetch gid from cache 544 -> S-1-5-32-544
>[2008/02/13 20:00:49, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089)
>  fetch gid from cache 10000 -> S-1-5-32-545
>[2008/02/13 20:00:49, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(|(objectclass=sambaGroupMapping)(sambaGroupType=4))(|(sambaSIDList=S-1-5-21-2953885461-2572836322-187695268-21000)(sambaSIDList=S-1-5-21-2953885461-2572836322-187695268-513)(sambaSIDList=S-1-1-0)(sambaSIDList=S-1-5-2)(sambaSIDList=S-1-5-11)(sambaSIDList=S-1-22-2-513)))], scope => [2]
>[2008/02/13 20:00:49, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(|(objectclass=sambaGroupMapping)(sambaGroupType=4))(|(sambaSIDList=S-1-5-21-2953885461-2572836322-187695268-21000)(sambaSIDList=S-1-5-21-2953885461-2572836322-187695268-513)(sambaSIDList=S-1-1-0)(sambaSIDList=S-1-5-2)(sambaSIDList=S-1-5-11)(sambaSIDList=S-1-22-2-513)))], scope => [2]
>[2008/02/13 20:00:49, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2953885461-2572836322-187695268-21000]
>[2008/02/13 20:00:49, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2953885461-2572836322-187695268-513]
>[2008/02/13 20:00:49, 5] lib/privileges.c:get_privileges_for_sids(460)
>  get_privileges_for_sids: sid = S-1-1-0
>  Privilege set:
>  SE_PRIV  0x0 0x0 0x0 0x0
>[2008/02/13 20:00:49, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2008/02/13 20:00:49, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-11]
>[2008/02/13 20:00:49, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-22-2-513]
>[2008/02/13 20:00:49, 3] lib/privileges.c:get_privileges(261)
>  get_privileges: No privileges assigned to SID [S-1-5-32-545]
>[2008/02/13 20:00:49, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089)
>  fetch gid from cache 513 -> S-1-5-21-2953885461-2572836322-187695268-513
>[2008/02/13 20:00:49, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089)
>  fetch gid from cache 10000 -> S-1-5-32-545
>[2008/02/13 20:00:49, 3] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(338)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2008/02/13 20:00:49, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(63)
>  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_128
>    NTLMSSP_NEGOTIATE_KEY_EXCH
>    NTLMSSP_NEGOTIATE_56
>[2008/02/13 20:00:49, 3] smbd/password.c:register_vuid(280)
>  User name: asalaices	Real name: Jorge Adrian Salaices
>[2008/02/13 20:00:49, 3] smbd/password.c:register_vuid(301)
>  UNIX uid 10000 is UNIX user asalaices, and will be vuid 103
>[2008/02/13 20:00:49, 3] smbd/password.c:register_vuid(332)
>  Adding homes service for user 'asalaices' using home directory: '/home/asalaices'
>[2008/02/13 20:00:49, 3] param/loadparm.c:lp_add_home(2663)
>  adding home's share [asalaices] for user 'asalaices' at '/home/asalaices'
>[2008/02/13 20:00:49, 6] param/loadparm.c:lp_file_list_changed(3073)
>  lp_file_list_changed()
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed Feb 13 19:21:36 2008
>  
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=132
>  smb_com=0x73
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=103
>  smb_mid=384
>  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=89
>[2008/02/13 20:00:49, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x5e
>[2008/02/13 20:00:49, 3] smbd/process.c:process_smb(1068)
>  Transaction 8 of length 98
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=94
>  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=103
>  smb_mid=448
>  smt_wct=4
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=   94 (0x5E)
>  smb_vwv[ 2]=    8 (0x8)
>  smb_vwv[ 3]=    1 (0x1)
>  smb_bcc=51
>[2008/02/13 20:00:49, 3] smbd/process.c:switch_message(926)
>  switch message SMBtconX (pid 23719) conn 0x0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:00:49, 4] smbd/reply.c:reply_tcon_and_X(506)
>  Client requested device type [?????] for share [SHOPTEST]
>[2008/02/13 20:00:49, 5] smbd/service.c:make_connection(1205)
>  making a connection to 'normal' service shoptest
>[2008/02/13 20:00:49, 3] lib/util_sid.c:string_to_sid(223)
>  string_to_sid: Sid @domain admins does not start with 'S-'.
>[2008/02/13 20:00:49, 5] smbd/password.c:user_in_netgroup(446)
>  Unable to get default yp domain, let's try without specifying it
>[2008/02/13 20:00:49, 5] smbd/password.c:user_in_netgroup(450)
>  looking for user asalaices of domain (ANY) in netgroup domain admins
>[2008/02/13 20:00:49, 5] smbd/password.c:user_in_netgroup(466)
>  looking for user asalaices of domain (ANY) in netgroup domain admins
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(objectClass=sambaGroupMapping)(|(displayName=domain admins)(cn=domain admins)))], scope => [2]
>[2008/02/13 20:00:49, 2] passdb/pdb_ldap.c:init_group_from_ldap(2158)
>  init_group_from_ldap: Entry found for group: 512
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] lib/util_sid.c:string_to_sid(223)
>  string_to_sid: Sid @shop does not start with 'S-'.
>[2008/02/13 20:00:49, 5] smbd/password.c:user_in_netgroup(446)
>  Unable to get default yp domain, let's try without specifying it
>[2008/02/13 20:00:49, 5] smbd/password.c:user_in_netgroup(450)
>  looking for user asalaices of domain (ANY) in netgroup shop
>[2008/02/13 20:00:49, 5] smbd/password.c:user_in_netgroup(466)
>  looking for user asalaices of domain (ANY) in netgroup shop
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 3] smbd/uid.c:push_conn_ctx(358)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] lib/smbldap.c:smbldap_search_ext(1182)
>  smbldap_search_ext: base => [ou=Groups,dc=avatas,dc=com], filter => [(&(objectClass=sambaGroupMapping)(|(displayName=shop)(cn=shop)))], scope => [2]
>[2008/02/13 20:00:49, 2] passdb/pdb_ldap.c:init_group_from_ldap(2158)
>  init_group_from_ldap: Entry found for group: 10001
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 2] smbd/service.c:make_connection_snum(616)
>  user 'asalaices' (from session setup) not permitted to access this share (shoptest)
>[2008/02/13 20:00:49, 3] smbd/error.c:error_packet_set(106)
>  error packet at smbd/reply.c(514) cmd=117 (SMBtconX) NT_STATUS_ACCESS_DENIED
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=35
>  smb_com=0x75
>  smb_rcls=34
>  smb_reh=0
>  smb_err=49152
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=103
>  smb_mid=448
>  smt_wct=0
>  smb_bcc=0
>[2008/02/13 20:00:49, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x27
>[2008/02/13 20:00:49, 3] smbd/process.c:process_smb(1068)
>  Transaction 9 of length 43
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=39
>  smb_com=0x74
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=103
>  smb_mid=512
>  smt_wct=2
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_bcc=0
>[2008/02/13 20:00:49, 3] smbd/process.c:switch_message(926)
>  switch message SMBulogoffX (pid 23719) conn 0x0
>[2008/02/13 20:00:49, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:00:49, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:00:49, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:00:49, 3] smbd/reply.c:reply_ulogoffX(1560)
>  ulogoffX vuid=103
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:00:49, 5] lib/util.c:show_msg(516)
>  size=39
>  smb_com=0x74
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=103
>  smb_mid=512
>  smt_wct=2
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_bcc=0
>[2008/02/13 20:01:00, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x27
>[2008/02/13 20:01:00, 3] smbd/process.c:process_smb(1068)
>  Transaction 10 of length 43
>[2008/02/13 20:01:00, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:01:00, 5] lib/util.c:show_msg(516)
>  size=39
>  smb_com=0x74
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=576
>  smt_wct=2
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_bcc=0
>[2008/02/13 20:01:00, 3] smbd/process.c:switch_message(926)
>  switch message SMBulogoffX (pid 23719) conn 0x0
>[2008/02/13 20:01:00, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:01:00, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:01:00, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:01:00, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:01:00, 3] smbd/reply.c:reply_ulogoffX(1560)
>  ulogoffX vuid=101
>[2008/02/13 20:01:00, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:01:00, 5] lib/util.c:show_msg(516)
>  size=39
>  smb_com=0x74
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=0
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=576
>  smt_wct=2
>  smb_vwv[ 0]=  255 (0xFF)
>  smb_vwv[ 1]=    0 (0x0)
>  smb_bcc=0
>[2008/02/13 20:01:00, 6] smbd/process.c:process_smb(1067)
>  got message type 0x0 of len 0x23
>[2008/02/13 20:01:00, 3] smbd/process.c:process_smb(1068)
>  Transaction 11 of length 39
>[2008/02/13 20:01:00, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:01:00, 5] lib/util.c:show_msg(516)
>  size=35
>  smb_com=0x71
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51207
>  smb_tid=1
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=640
>  smt_wct=0
>  smb_bcc=0
>[2008/02/13 20:01:00, 3] smbd/process.c:switch_message(926)
>  switch message SMBtdis (pid 23719) conn 0x916cf18
>[2008/02/13 20:01:00, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:01:00, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:01:00, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:01:00, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:01:00, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:01:00, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:01:00, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:01:00, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:01:00, 3] smbd/service.c:close_cnum(1230)
>  vwd-df-util1 (10.0.1.22) closed connection to service IPC$
>[2008/02/13 20:01:00, 3] smbd/connection.c:yield_connection(69)
>  Yielding connection to IPC$
>[2008/02/13 20:01:00, 4] smbd/vfs.c:vfs_ChDir(665)
>  vfs_ChDir to /
>[2008/02/13 20:01:00, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:01:00, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:01:00, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:01:00, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:01:00, 5] lib/util.c:show_msg(506)
>[2008/02/13 20:01:00, 5] lib/util.c:show_msg(516)
>  size=35
>  smb_com=0x71
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=136
>  smb_flg2=51201
>  smb_tid=1
>  smb_pid=65279
>  smb_uid=101
>  smb_mid=640
>  smt_wct=0
>  smb_bcc=0
>[2008/02/13 20:01:00, 3] smbd/process.c:timeout_processing(1328)
>  timeout_processing: End of file from client (client has disconnected).
>[2008/02/13 20:01:00, 5] lib/gencache.c:gencache_shutdown(94)
>  Closing cache file
>[2008/02/13 20:01:00, 5] libsmb/namecache.c:namecache_shutdown(79)
>  namecache_shutdown: netbios namecache closed successfully.
>[2008/02/13 20:01:00, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2008/02/13 20:01:00, 5] auth/auth_util.c:debug_nt_user_token(448)
>  NT user token: (NULL)
>[2008/02/13 20:01:00, 5] auth/auth_util.c:debug_unix_user_token(474)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2008/02/13 20:01:00, 5] smbd/uid.c:change_to_root_user(288)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2008/02/13 20:01:00, 3] smbd/connection.c:yield_connection(69)
>  Yielding connection to 
>[2008/02/13 20:01:00, 3] smbd/server.c:exit_server_common(768)
>  Server exit (normal exit)

Actions: View

Attachments on bug 5260: 3140 | 3141