[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)