[Samba] NT_STATUS_ACCESS_DENIED Causes?
Craig Stelter
stelter at sonic.net
Tue Dec 14 05:48:33 MST 2010
Hi Volker,
Thanks for the reply. I set my log level to 10. I can't spot
anything in this-- everything about the gid/uid looks reasonable (it
seems to know I'm uid=500) and the path is resolved right. But at the
very end it is just permission denied. Is there anything in this that
is obvious to you?
Here is the directory:
[cstelter at fedorabox ~]$ ls -al /share/system
total 56
drwxr-xr-x. 8 cstelter stelters 4096 Dec 13 15:35 .
drwxrwxrwx. 3 root root 4096 Dec 14 05:55 ..
drwxr-xr-x. 2 cstelter stelters 4096 Sep 17 2008 bitmaps
drwxr-xr-x. 4 cstelter stelters 4096 Feb 4 2007 Dad
-rw-r--r--. 1 cstelter stelters 5478 May 1 2010 flight1.mid
-rw-r--r--. 1 cstelter stelters 5478 May 1 2010 flight2.mid
-rw-r--r--. 1 cstelter stelters 5478 May 1 2010 flight3.mid
drwxr-xr-x. 38 cstelter stelters 4096 Oct 4 2005 infocom
drwxr-xr-x. 2 cstelter stelters 4096 Oct 4 2005 Infocom Games
drwxr-xr-x. 7 cstelter stelters 4096 Feb 12 2008 Program Files
drwxr-xr-x. 51 cstelter stelters 4096 Nov 17 16:33 Utilities
[cstelter at fedorabox ~]$ id
uid=500(cstelter) gid=500(cstelter)
groups=503(stelters),500(cstelter),502(domadm)
context=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023
And here is the logfile:
[2010/12/14 06:39:31.566242, 6]
param/loadparm.c:7133(lp_file_list_changed)
lp_file_list_changed()
file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Tue
Dec 14 05:28:19 2010
[2010/12/14 06:39:31.567091, 5] smbd/reply.c:606(reply_special)
init msg_type=0x81 msg_flags=0x0
[2010/12/14 06:39:31.569456, 10]
lib/util_sock.c:726(read_smb_length_return_keepalive)
got smb length of 190
[2010/12/14 06:39:31.570508, 6] smbd/process.c:1482(process_smb)
got message type 0x0 of len 0xbe
[2010/12/14 06:39:31.570768, 3] smbd/process.c:1485(process_smb)
Transaction 0 of length 194 (0 toread)
[2010/12/14 06:39:31.571016, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:31.571180, 5] lib/util.c:627(show_msg)
size=190
smb_com=0x72
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=8
smb_flg2=51201
smb_tid=0
smb_pid=12676
smb_uid=0
smb_mid=2
smt_wct=0
smb_bcc=155
[2010/12/14 06:39:31.571835, 10] ../lib/util/util.c:278(_dump_data)
[0000] 02 50 43 20 4E 45 54 57 4F 52 4B 20 50 52 4F 47 .PC NETW
ORK PROG
[0010] 52 41 4D 20 31 2E 30 00 02 4D 49 43 52 4F 53 4F RAM
1.0. .MICROSO
[0020] 46 54 20 4E 45 54 57 4F 52 4B 53 20 31 2E 30 33 FT NETWO
RKS 1.03
[0030] 00 02 4D 49 43 52 4F 53 4F 46 54 20 4E 45 54 57 ..MICROS
OFT NETW
[0040] 4F 52 4B 53 20 33 2E 30 00 02 4C 41 4E 4D 41 4E ORKS
3.0 ..LANMAN
[0050] 31 2E 30 00 02 4C 4D 31 2E 32 58 30 30 32 00 02
1.0..LM1 .2X002..
[0060] 44 4F 53 20 4C 41 4E 4D 41 4E 32 2E 31 00 02 4C DOS LANM
AN2.1..L
[0070] 41 4E 4D 41 4E 32 2E 31 00 02 53 61 6D 62 61 00
ANMAN2.1 ..Samba.
[0080] 02 4E 54 20 4C 41 4E 4D 41 4E 20 31 2E 30 00 02 .NT LANM AN
1.0..
[0090] 4E 54 20 4C 4D 20 30 2E 31 32 00 NT LM 0. 12.
[2010/12/14 06:39:31.583672, 3] smbd/process.c:1294(switch_message)
switch message SMBnegprot (pid 12678) conn 0x0
[2010/12/14 06:39:31.584061, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.584322, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.584518, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.584837, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:31.585550, 3] smbd/negprot.c:586(reply_negprot)
Requested protocol [PC NETWORK PROGRAM 1.0]
[2010/12/14 06:39:31.585907, 3] smbd/negprot.c:586(reply_negprot)
Requested protocol [MICROSOFT NETWORKS 1.03]
[2010/12/14 06:39:31.593372, 3] smbd/negprot.c:586(reply_negprot)
Requested protocol [MICROSOFT NETWORKS 3.0]
[2010/12/14 06:39:31.593610, 3] smbd/negprot.c:586(reply_negprot)
Requested protocol [LANMAN1.0]
[2010/12/14 06:39:31.593808, 3] smbd/negprot.c:586(reply_negprot)
Requested protocol [LM1.2X002]
[2010/12/14 06:39:31.594094, 3] smbd/negprot.c:586(reply_negprot)
Requested protocol [DOS LANMAN2.1]
[2010/12/14 06:39:31.594312, 3] smbd/negprot.c:586(reply_negprot)
Requested protocol [LANMAN2.1]
[2010/12/14 06:39:31.594500, 3] smbd/negprot.c:586(reply_negprot)
Requested protocol [Samba]
[2010/12/14 06:39:31.594688, 3] smbd/negprot.c:586(reply_negprot)
Requested protocol [NT LANMAN 1.0]
[2010/12/14 06:39:31.594883, 3] smbd/negprot.c:586(reply_negprot)
Requested protocol [NT LM 0.12]
[2010/12/14 06:39:31.595244, 10] lib/util.c:1969(set_remote_arch)
set_remote_arch: Client arch is 'Samba'
[2010/12/14 06:39:31.595625, 6]
param/loadparm.c:7133(lp_file_list_changed)
lp_file_list_changed()
file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Tue
Dec 14 05:28:19 2010
[2010/12/14 06:39:31.603404, 5] smbd/connection.c:142(claim_connection)
claiming []
[2010/12/14 06:39:31.603927, 10]
lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
Locking key 86310000FFFFFFFFFFFF
[2010/12/14 06:39:31.604342, 10]
lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
Allocated locked data 0x0x14a5ef8
[2010/12/14 06:39:31.604892, 10]
lib/dbwrap_tdb.c:42(db_tdb_record_destr)
Unlocking key 86310000FFFFFFFFFFFF
[2010/12/14 06:39:31.605763, 6]
param/loadparm.c:7133(lp_file_list_changed)
lp_file_list_changed()
file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Tue
Dec 14 05:28:19 2010
[2010/12/14 06:39:31.606631, 3] smbd/negprot.c:404(reply_nt1)
using SPNEGO
[2010/12/14 06:39:31.606886, 3] smbd/negprot.c:691(reply_negprot)
Selected protocol NT LANMAN 1.0
[2010/12/14 06:39:31.609540, 5] smbd/negprot.c:698(reply_negprot)
negprot index=8
[2010/12/14 06:39:31.609770, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:31.609870, 5] lib/util.c:627(show_msg)
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=12676
smb_uid=0
smb_mid=2
smt_wct=17
smb_vwv[ 0]= 8 (0x8)
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]=34304 (0x8600)
smb_vwv[ 8]= 49 (0x31)
smb_vwv[ 9]=64768 (0xFD00)
smb_vwv[10]=33011 (0x80F3)
smb_vwv[11]=32896 (0x8080)
smb_vwv[12]=13315 (0x3403)
smb_vwv[13]=35828 (0x8BF4)
smb_vwv[14]=52123 (0xCB9B)
smb_vwv[15]=26625 (0x6801)
smb_vwv[16]= 1 (0x1)
smb_bcc=58
[2010/12/14 06:39:31.611404, 10] ../lib/util/util.c:278(_dump_data)
[0000] 66 65 64 6F 72 61 62 6F 78 00 00 00 00 00 00 00 fedorabo
x.......
[0010] 60 28 06 06 2B 06 01 05 05 02 A0 1E 30 1C A0 0E
`(..+... ....0...
[0020] 30 0C 06 0A 2B 06 01 04 01 82 37 02 02 0A A3 0A
0...+... ..7.....
[0030] 30 08 A0 06 1B 04 4E 4F 4E 45 0.....NO NE
[2010/12/14 06:39:31.614465, 10]
lib/util_sock.c:726(read_smb_length_return_keepalive)
got smb length of 164
[2010/12/14 06:39:31.614876, 6] smbd/process.c:1482(process_smb)
got message type 0x0 of len 0xa4
[2010/12/14 06:39:31.615179, 3] smbd/process.c:1485(process_smb)
Transaction 1 of length 168 (0 toread)
[2010/12/14 06:39:31.615367, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:31.615482, 5] lib/util.c:627(show_msg)
size=164
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=8
smb_flg2=51201
smb_tid=0
smb_pid=12676
smb_uid=0
smb_mid=3
smt_wct=12
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
smb_vwv[ 2]=65535 (0xFFFF)
smb_vwv[ 3]= 2 (0x2)
smb_vwv[ 4]= 1 (0x1)
smb_vwv[ 5]= 0 (0x0)
smb_vwv[ 6]= 0 (0x0)
smb_vwv[ 7]= 82 (0x52)
smb_vwv[ 8]= 0 (0x0)
smb_vwv[ 9]= 0 (0x0)
smb_vwv[10]=53340 (0xD05C)
smb_vwv[11]=32768 (0x8000)
smb_bcc=105
[2010/12/14 06:39:31.616773, 10] ../lib/util/util.c:278(_dump_data)
[0000] 60 50 06 06 2B 06 01 05 05 02 A0 46 30 44 A0 0E
`P..+... ...F0D..
[0010] 30 0C 06 0A 2B 06 01 04 01 82 37 02 02 0A A2 32
0...+... ..7....2
[0020] 04 30 4E 54 4C 4D 53 53 50 00 01 00 00 00 15 82 .0NTLMSS
P.......
[0030] 08 60 07 00 07 00 20 00 00 00 09 00 09 00 27
00 .`.... . ......'.
[0040] 00 00 53 54 45 4C 54 45 52 46 45 44 4F 52 41 42 ..STELTE
RFEDORAB
[0050] 4F 58 00 55 00 6E 00 69 00 78 00 00 00 53 00 61
OX.U.n.i .x...S.a
[0060] 00 6D 00 62 00 61 00 00 00 .m.b.a.. .
[2010/12/14 06:39:31.617785, 3] smbd/process.c:1294(switch_message)
switch message SMBsesssetupX (pid 12678) conn 0x0
[2010/12/14 06:39:31.618066, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.618263, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.618425, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.618708, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:31.619000, 3]
smbd/sesssetup.c:1436(reply_sesssetup_and_X)
wct=12 flg2=0xc801
[2010/12/14 06:39:31.619268, 3]
smbd/sesssetup.c:1190(reply_sesssetup_and_X_spnego)
Doing spnego session setup
[2010/12/14 06:39:31.619477, 3]
smbd/sesssetup.c:1232(reply_sesssetup_and_X_spnego)
NativeOS=[Unix] NativeLanMan=[Samba] PrimaryDomain=[]
[2010/12/14 06:39:31.619682, 10]
smbd/password.c:184(register_initial_vuid)
register_initial_vuid: allocated vuid = 100
[2010/12/14 06:39:31.619927, 10]
smbd/sesssetup.c:1135(check_spnego_blob_complete)
check_spnego_blob_complete: needed_len = 82, pblob->length = 82
[2010/12/14 06:39:31.620441, 5]
smbd/sesssetup.c:754(parse_spnego_mechanisms)
parse_spnego_mechanisms: Got OID 1.3.6.1.4.1.311.2.2.10
[2010/12/14 06:39:31.620805, 3]
smbd/sesssetup.c:806(reply_spnego_negotiate)
reply_spnego_negotiate: Got secblob of size 48
[2010/12/14 06:39:31.621211, 5]
auth/auth.c:481(make_auth_context_subsystem)
Making default auth method list for DC, security=user, encrypt
passwords = yes
[2010/12/14 06:39:31.621517, 5] auth/auth.c:46(smb_register_auth)
Attempting to register auth backend sam
[2010/12/14 06:39:31.621721, 5] auth/auth.c:58(smb_register_auth)
Successfully added auth method 'sam'
[2010/12/14 06:39:31.621891, 5] auth/auth.c:46(smb_register_auth)
Attempting to register auth backend sam_ignoredomain
[2010/12/14 06:39:31.622175, 5] auth/auth.c:58(smb_register_auth)
Successfully added auth method 'sam_ignoredomain'
[2010/12/14 06:39:31.622362, 5] auth/auth.c:46(smb_register_auth)
Attempting to register auth backend unix
[2010/12/14 06:39:31.622537, 5] auth/auth.c:58(smb_register_auth)
Successfully added auth method 'unix'
[2010/12/14 06:39:31.622711, 5] auth/auth.c:46(smb_register_auth)
Attempting to register auth backend winbind
[2010/12/14 06:39:31.622897, 5] auth/auth.c:58(smb_register_auth)
Successfully added auth method 'winbind'
[2010/12/14 06:39:31.623148, 5] auth/auth.c:46(smb_register_auth)
Attempting to register auth backend wbc
[2010/12/14 06:39:31.623338, 5] auth/auth.c:58(smb_register_auth)
Successfully added auth method 'wbc'
[2010/12/14 06:39:31.623513, 5] auth/auth.c:46(smb_register_auth)
Attempting to register auth backend smbserver
[2010/12/14 06:39:31.623698, 5] auth/auth.c:58(smb_register_auth)
Successfully added auth method 'smbserver'
[2010/12/14 06:39:31.623876, 5] auth/auth.c:46(smb_register_auth)
Attempting to register auth backend trustdomain
[2010/12/14 06:39:31.624144, 5] auth/auth.c:58(smb_register_auth)
Successfully added auth method 'trustdomain'
[2010/12/14 06:39:31.624324, 5] auth/auth.c:46(smb_register_auth)
Attempting to register auth backend ntdomain
[2010/12/14 06:39:31.624496, 5] auth/auth.c:58(smb_register_auth)
Successfully added auth method 'ntdomain'
[2010/12/14 06:39:31.624663, 5] auth/auth.c:46(smb_register_auth)
Attempting to register auth backend guest
[2010/12/14 06:39:31.624829, 5] auth/auth.c:58(smb_register_auth)
Successfully added auth method 'guest'
[2010/12/14 06:39:31.625080, 5] auth/auth.c:46(smb_register_auth)
Attempting to register auth backend netlogond
[2010/12/14 06:39:31.625302, 5] auth/auth.c:58(smb_register_auth)
Successfully added auth method 'netlogond'
[2010/12/14 06:39:31.625474, 5] auth/auth.c:383(load_auth_module)
load_auth_module: Attempting to find an auth method to match guest
[2010/12/14 06:39:31.625668, 5] auth/auth.c:408(load_auth_module)
load_auth_module: auth method guest has a valid init
[2010/12/14 06:39:31.625857, 5] auth/auth.c:383(load_auth_module)
load_auth_module: Attempting to find an auth method to match sam
[2010/12/14 06:39:31.626117, 5] auth/auth.c:408(load_auth_module)
load_auth_module: auth method sam has a valid init
[2010/12/14 06:39:31.626319, 5] auth/auth.c:383(load_auth_module)
load_auth_module: Attempting to find an auth method to match
winbind:trustdomain
[2010/12/14 06:39:31.626467, 5] auth/auth.c:383(load_auth_module)
load_auth_module: Attempting to find an auth method to match
trustdomain
[2010/12/14 06:39:31.626613, 5] auth/auth.c:408(load_auth_module)
load_auth_module: auth method trustdomain has a valid init
[2010/12/14 06:39:31.626753, 5] auth/auth.c:408(load_auth_module)
load_auth_module: auth method winbind has a valid init
[2010/12/14 06:39:31.629888, 3]
libsmb/ntlmssp.c:65(debug_ntlmssp_flags)
Got NTLMSSP neg_flags=0x60088215
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
[2010/12/14 06:39:31.630827,
1] ../librpc/ndr/ndr.c:214(ndr_print_debug)
&negotiate: struct NEGOTIATE_MESSAGE
Signature : 'NTLMSSP'
MessageType : NtLmNegotiate (1)
NegotiateFlags : 0x60088215 (1611170325)
1: NTLMSSP_NEGOTIATE_UNICODE
0: NTLMSSP_NEGOTIATE_OEM
1: NTLMSSP_REQUEST_TARGET
1: NTLMSSP_NEGOTIATE_SIGN
0: NTLMSSP_NEGOTIATE_SEAL
0: NTLMSSP_NEGOTIATE_DATAGRAM
0: NTLMSSP_NEGOTIATE_LM_KEY
0: NTLMSSP_NEGOTIATE_NETWARE
1: NTLMSSP_NEGOTIATE_NTLM
0: NTLMSSP_NEGOTIATE_NT_ONLY
0: NTLMSSP_ANONYMOUS
0: NTLMSSP_NEGOTIATE_OEM_DOMAIN_SUPPLIED
0: NTLMSSP_NEGOTIATE_OEM_WORKSTATION_SUPPLIED
0: NTLMSSP_NEGOTIATE_THIS_IS_LOCAL_CALL
1: NTLMSSP_NEGOTIATE_ALWAYS_SIGN
0: NTLMSSP_TARGET_TYPE_DOMAIN
0: NTLMSSP_TARGET_TYPE_SERVER
0: NTLMSSP_TARGET_TYPE_SHARE
1: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY
0: NTLMSSP_NEGOTIATE_IDENTIFY
0: NTLMSSP_REQUEST_NON_NT_SESSION_KEY
0: NTLMSSP_NEGOTIATE_TARGET_INFO
0: NTLMSSP_NEGOTIATE_VERSION
1: NTLMSSP_NEGOTIATE_128
1: NTLMSSP_NEGOTIATE_KEY_EXCH
0: NTLMSSP_NEGOTIATE_56
DomainNameLen : 0x0007 (7)
DomainNameMaxLen : 0x0007 (7)
DomainName : *
DomainName : 'STELTER'
WorkstationLen : 0x0009 (9)
WorkstationMaxLen : 0x0009 (9)
Workstation : *
Workstation : 'FEDORABOX'
[2010/12/14 06:39:31.633613, 5] auth/auth.c:97(get_ntlm_challenge)
auth_get_challenge: module guest did not want to specify a challenge
[2010/12/14 06:39:31.633845, 5] auth/auth.c:97(get_ntlm_challenge)
auth_get_challenge: module sam did not want to specify a challenge
[2010/12/14 06:39:31.634098, 5] auth/auth.c:97(get_ntlm_challenge)
auth_get_challenge: module winbind did not want to specify a challenge
[2010/12/14 06:39:31.634363, 5] auth/auth.c:132(get_ntlm_challenge)
auth_context challenge created by random
[2010/12/14 06:39:31.634559, 5] auth/auth.c:133(get_ntlm_challenge)
challenge is:
[2010/12/14 06:39:31.634723, 5] ../lib/util/util.c:278(_dump_data)
[0000] 20 C5 10 36 A6 DC C5 48 ..6...H
[2010/12/14 06:39:31.637768,
1] ../librpc/ndr/ndr.c:214(ndr_print_debug)
&challenge: struct CHALLENGE_MESSAGE
Signature : 'NTLMSSP'
MessageType : NtLmChallenge (0x2)
TargetNameLen : 0x000e (14)
TargetNameMaxLen : 0x000e (14)
TargetName : *
TargetName : 'STELTER'
NegotiateFlags : 0x60898215 (1619624469)
1: NTLMSSP_NEGOTIATE_UNICODE
0: NTLMSSP_NEGOTIATE_OEM
1: NTLMSSP_REQUEST_TARGET
1: NTLMSSP_NEGOTIATE_SIGN
0: NTLMSSP_NEGOTIATE_SEAL
0: NTLMSSP_NEGOTIATE_DATAGRAM
0: NTLMSSP_NEGOTIATE_LM_KEY
0: NTLMSSP_NEGOTIATE_NETWARE
1: NTLMSSP_NEGOTIATE_NTLM
0: NTLMSSP_NEGOTIATE_NT_ONLY
0: NTLMSSP_ANONYMOUS
0: NTLMSSP_NEGOTIATE_OEM_DOMAIN_SUPPLIED
0: NTLMSSP_NEGOTIATE_OEM_WORKSTATION_SUPPLIED
0: NTLMSSP_NEGOTIATE_THIS_IS_LOCAL_CALL
1: NTLMSSP_NEGOTIATE_ALWAYS_SIGN
1: NTLMSSP_TARGET_TYPE_DOMAIN
0: NTLMSSP_TARGET_TYPE_SERVER
0: NTLMSSP_TARGET_TYPE_SHARE
1: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY
0: NTLMSSP_NEGOTIATE_IDENTIFY
0: NTLMSSP_REQUEST_NON_NT_SESSION_KEY
1: NTLMSSP_NEGOTIATE_TARGET_INFO
0: NTLMSSP_NEGOTIATE_VERSION
1: NTLMSSP_NEGOTIATE_128
1: NTLMSSP_NEGOTIATE_KEY_EXCH
0: NTLMSSP_NEGOTIATE_56
ServerChallenge : 20c51036a6dcc548
Reserved : 0000000000000000
TargetInfoLen : 0x0046 (70)
TargetNameInfoMaxLen : 0x0046 (70)
TargetInfo : *
TargetInfo: struct AV_PAIR_LIST
count : 0x00000005 (5)
pair: ARRAY(5)
pair: struct AV_PAIR
AvId : MsvAvNbDomainName
(0x2)
AvLen : 0x000e (14)
Value : union
ntlmssp_AvValue(case 0x2)
AvNbDomainName : 'STELTER'
pair: struct AV_PAIR
AvId : MsvAvNbComputerName
(0x1)
AvLen : 0x0012 (18)
Value : union
ntlmssp_AvValue(case 0x1)
AvNbComputerName : 'FEDORABOX'
pair: struct AV_PAIR
AvId : MsvAvDnsDomainName
(0x4)
AvLen : 0x0000 (0)
Value : union
ntlmssp_AvValue(case 0x4)
AvDnsDomainName : ''
pair: struct AV_PAIR
AvId :
MsvAvDnsComputerName (0x3)
AvLen : 0x0012 (18)
Value : union
ntlmssp_AvValue(case 0x3)
AvDnsComputerName : 'fedorabox'
pair: struct AV_PAIR
AvId : MsvAvEOL (0x0)
AvLen : 0x0000 (0)
Value : union
ntlmssp_AvValue(case 0x0)
[2010/12/14 06:39:31.642886, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:31.643144, 5] lib/util.c:627(show_msg)
size=272
smb_com=0x73
smb_rcls=22
smb_reh=0
smb_err=49152
smb_flg=136
smb_flg2=51203
smb_tid=0
smb_pid=12676
smb_uid=100
smb_mid=3
smt_wct=4
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
smb_vwv[ 2]= 0 (0x0)
smb_vwv[ 3]= 163 (0xA3)
smb_bcc=229
[2010/12/14 06:39:31.644032, 10] ../lib/util/util.c:278(_dump_data)
[0000] A1 81 A0 30 81 9D A0 03 0A 01 01 A1 0C 06 0A
2B ...0.... .......+
[0010] 06 01 04 01 82 37 02 02 0A A2 81 87 04 81 84
4E .....7.. .......N
[0020] 54 4C 4D 53 53 50 00 02 00 00 00 0E 00 0E 00 30
TLMSSP.. .......0
[0030] 00 00 00 15 82 89 60 20 C5 10 36 A6 DC C5 48
00 ......` ..6...H.
[0040] 00 00 00 00 00 00 00 46 00 46 00 3E 00 00 00
53 .......F .F.>...S
[0050] 00 54 00 45 00 4C 00 54 00 45 00 52 00 02 00
0E .T.E.L.T .E.R....
[0060] 00 53 00 54 00 45 00 4C 00 54 00 45 00 52 00
01 .S.T.E.L .T.E.R..
[0070] 00 12 00 46 00 45 00 44 00 4F 00 52 00 41 00
42 ...F.E.D .O.R.A.B
[0080] 00 4F 00 58 00 04 00 00 00 03 00 12 00 66 00
65 .O.X.... .....f.e
[0090] 00 64 00 6F 00 72 00 61 00 62 00 6F 00 78 00
00 .d.o.r.a .b.o.x..
[00A0] 00 00 00 55 00 6E 00 69 00 78 00 00 00 53 00
61 ...U.n.i .x...S.a
[00B0] 00 6D 00 62 00 61 00 20 00 33 00 2E 00 35 00
2E .m.b.a. .3...5..
[00C0] 00 36 00 2D 00 37 00 30 00 2E 00 66 00 63 00
31 .6.-.7.0 ...f.c.1
[00D0] 00 34 00 00 00 53 00 54 00 45 00 4C 00 54 00
45 .4...S.T .E.L.T.E
[00E0] 00 52 00 00 00 .R...
[2010/12/14 06:39:31.648282, 10]
lib/util_sock.c:726(read_smb_length_return_keepalive)
got smb length of 270
[2010/12/14 06:39:31.648702, 6] smbd/process.c:1482(process_smb)
got message type 0x0 of len 0x10e
[2010/12/14 06:39:31.649105, 3] smbd/process.c:1485(process_smb)
Transaction 2 of length 274 (0 toread)
[2010/12/14 06:39:31.649342, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:31.649465, 5] lib/util.c:627(show_msg)
size=270
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=8
smb_flg2=51201
smb_tid=0
smb_pid=12676
smb_uid=100
smb_mid=4
smt_wct=12
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
smb_vwv[ 2]=65535 (0xFFFF)
smb_vwv[ 3]= 2 (0x2)
smb_vwv[ 4]= 1 (0x1)
smb_vwv[ 5]= 0 (0x0)
smb_vwv[ 6]= 0 (0x0)
smb_vwv[ 7]= 188 (0xBC)
smb_vwv[ 8]= 0 (0x0)
smb_vwv[ 9]= 0 (0x0)
smb_vwv[10]=53340 (0xD05C)
smb_vwv[11]=32768 (0x8000)
smb_bcc=211
[2010/12/14 06:39:31.650731, 10] ../lib/util/util.c:278(_dump_data)
[0000] A1 81 B9 30 81 B6 A2 81 B3 04 81 B0 4E 54 4C
4D ...0.... ....NTLM
[0010] 53 53 50 00 03 00 00 00 18 00 18 00 40 00 00 00
SSP..... .... at ...
[0020] 18 00 18 00 58 00 00 00 0E 00 0E 00 70 00 00
00 ....X... ....p...
[0030] 10 00 10 00 7E 00 00 00 12 00 12 00 8E 00 00
00 ....~... ........
[0040] 10 00 10 00 A0 00 00 00 15 82 08 60 8C C7 B4
63 ........ ...`...c
[0050] 4F 95 8E 10 00 00 00 00 00 00 00 00 00 00 00 00
O....... ........
[0060] 00 00 00 00 C0 35 1A 39 AF BE B6 A7 B3 3F 63
DE .....5.9 .....?c.
[0070] B4 64 94 D9 0F A4 8B 38 BD 22 EF E5 53 00 54
00 .d.....8 ."..S.T.
[0080] 45 00 4C 00 54 00 45 00 52 00 63 00 73 00 74 00 E.L.T.E.
R.c.s.t.
[0090] 65 00 6C 00 74 00 65 00 72 00 46 00 45 00 44 00 e.l.t.e.
r.F.E.D.
[00A0] 4F 00 52 00 41 00 42 00 4F 00 58 00 16 A1 78 72 O.R.A.B.
O.X...xr
[00B0] EB 47 6C B1 D9 CE 59 81 F1 49 FB 5C 00 55 00
6E .Gl...Y. .I.\.U.n
[00C0] 00 69 00 78 00 00 00 53 00 61 00 6D 00 62 00
61 .i.x...S .a.m.b.a
[00D0] 00 00 00 ...
[2010/12/14 06:39:31.652539, 3] smbd/process.c:1294(switch_message)
switch message SMBsesssetupX (pid 12678) conn 0x0
[2010/12/14 06:39:31.652761, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.652994, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.653198, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.653489, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:31.653703, 3]
smbd/sesssetup.c:1436(reply_sesssetup_and_X)
wct=12 flg2=0xc801
[2010/12/14 06:39:31.653890, 3]
smbd/sesssetup.c:1190(reply_sesssetup_and_X_spnego)
Doing spnego session setup
[2010/12/14 06:39:31.654191, 3]
smbd/sesssetup.c:1232(reply_sesssetup_and_X_spnego)
NativeOS=[Unix] NativeLanMan=[Samba] PrimaryDomain=[]
[2010/12/14 06:39:31.654430, 10]
smbd/sesssetup.c:1135(check_spnego_blob_complete)
check_spnego_blob_complete: needed_len = 188, pblob->length = 188
[2010/12/14 06:39:31.654909,
1] ../librpc/ndr/ndr.c:214(ndr_print_debug)
&authenticate: struct AUTHENTICATE_MESSAGE
Signature : 'NTLMSSP'
MessageType : NtLmAuthenticate (3)
LmChallengeResponseLen : 0x0018 (24)
LmChallengeResponseMaxLen: 0x0018 (24)
LmChallengeResponse : *
LmChallengeResponse : union ntlmssp_LM_RESPONSE(case
24)
v1: struct LM_RESPONSE
Response :
8cc7b4634f958e1000000000000000000000000000000000
NtChallengeResponseLen : 0x0018 (24)
NtChallengeResponseMaxLen: 0x0018 (24)
NtChallengeResponse : *
NtChallengeResponse : union
ntlmssp_NTLM_RESPONSE(case 24)
v1: struct NTLM_RESPONSE
Response :
c0351a39afbeb6a7b33f63deb46494d90fa48b38bd22efe5
DomainNameLen : 0x000e (14)
DomainNameMaxLen : 0x000e (14)
DomainName : *
DomainName : 'STELTER'
UserNameLen : 0x0010 (16)
UserNameMaxLen : 0x0010 (16)
UserName : *
UserName : 'cstelter'
WorkstationLen : 0x0012 (18)
WorkstationMaxLen : 0x0012 (18)
Workstation : *
Workstation : 'FEDORABOX'
EncryptedRandomSessionKeyLen: 0x0010 (16)
EncryptedRandomSessionKeyMaxLen: 0x0010 (16)
EncryptedRandomSessionKey: *
EncryptedRandomSessionKey: DATA_BLOB length=16
[2010/12/14 06:39:31.657438, 10] ../lib/util/util.c:278(_dump_data)
[0000] 16 A1 78 72 EB 47 6C B1 D9 CE 59 81 F1 49 FB
5C ..xr.Gl. ..Y..I.\
NegotiateFlags : 0x60088215 (1611170325)
1: NTLMSSP_NEGOTIATE_UNICODE
0: NTLMSSP_NEGOTIATE_OEM
1: NTLMSSP_REQUEST_TARGET
1: NTLMSSP_NEGOTIATE_SIGN
0: NTLMSSP_NEGOTIATE_SEAL
0: NTLMSSP_NEGOTIATE_DATAGRAM
0: NTLMSSP_NEGOTIATE_LM_KEY
0: NTLMSSP_NEGOTIATE_NETWARE
1: NTLMSSP_NEGOTIATE_NTLM
0: NTLMSSP_NEGOTIATE_NT_ONLY
0: NTLMSSP_ANONYMOUS
0: NTLMSSP_NEGOTIATE_OEM_DOMAIN_SUPPLIED
0: NTLMSSP_NEGOTIATE_OEM_WORKSTATION_SUPPLIED
0: NTLMSSP_NEGOTIATE_THIS_IS_LOCAL_CALL
1: NTLMSSP_NEGOTIATE_ALWAYS_SIGN
0: NTLMSSP_TARGET_TYPE_DOMAIN
0: NTLMSSP_TARGET_TYPE_SERVER
0: NTLMSSP_TARGET_TYPE_SHARE
1: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY
0: NTLMSSP_NEGOTIATE_IDENTIFY
0: NTLMSSP_REQUEST_NON_NT_SESSION_KEY
0: NTLMSSP_NEGOTIATE_TARGET_INFO
0: NTLMSSP_NEGOTIATE_VERSION
1: NTLMSSP_NEGOTIATE_128
1: NTLMSSP_NEGOTIATE_KEY_EXCH
0: NTLMSSP_NEGOTIATE_56
[2010/12/14 06:39:31.659477, 3]
libsmb/ntlmssp.c:747(ntlmssp_server_auth)
Got user=[cstelter] domain=[STELTER] workstation=[FEDORABOX] len1=24
len2=24
[2010/12/14 06:39:31.659743, 5]
auth/auth_ntlmssp.c:70(auth_ntlmssp_set_challenge)
auth_context challenge set by NTLMSSP callback (NTLM2)
[2010/12/14 06:39:31.659898, 5]
auth/auth_ntlmssp.c:71(auth_ntlmssp_set_challenge)
challenge is:
[2010/12/14 06:39:31.663764, 5] ../lib/util/util.c:278(_dump_data)
[0000] 67 C4 FA EE CC F9 F8 96 g.......
[2010/12/14 06:39:31.664354, 6]
param/loadparm.c:7133(lp_file_list_changed)
lp_file_list_changed()
file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Tue
Dec 14 05:28:19 2010
[2010/12/14 06:39:31.664878, 5]
auth/auth_util.c:211(make_user_info_map)
Mapping user [STELTER]\[cstelter] from workstation [FEDORABOX]
[2010/12/14 06:39:31.665222, 5] auth/auth_util.c:122(make_user_info)
attempting to make a user_info for cstelter (cstelter)
[2010/12/14 06:39:31.665438, 5] auth/auth_util.c:132(make_user_info)
making strings for cstelter's user_info struct
[2010/12/14 06:39:31.665612, 5] auth/auth_util.c:164(make_user_info)
making blobs for cstelter's user_info struct
[2010/12/14 06:39:31.665787, 10] auth/auth_util.c:182(make_user_info)
made an encrypted user_info for cstelter (cstelter)
[2010/12/14 06:39:31.666040, 3] auth/auth.c:216(check_ntlm_password)
check_ntlm_password: Checking password for unmapped user
[STELTER]\[cstelter]@[FEDORABOX] with the new password interface
[2010/12/14 06:39:31.666251, 3] auth/auth.c:219(check_ntlm_password)
check_ntlm_password: mapped user is: [STELTER]\[cstelter]@[FEDORABOX]
[2010/12/14 06:39:31.666427, 10] auth/auth.c:228(check_ntlm_password)
check_ntlm_password: auth_context challenge created by NTLMSSP
callback (NTLM2)
[2010/12/14 06:39:31.666601, 10] auth/auth.c:230(check_ntlm_password)
challenge is:
[2010/12/14 06:39:31.666766, 5] ../lib/util/util.c:278(_dump_data)
[0000] 67 C4 FA EE CC F9 F8 96 g.......
[2010/12/14 06:39:31.667213, 10] auth/auth.c:256(check_ntlm_password)
check_ntlm_password: guest had nothing to say
[2010/12/14 06:39:31.667462, 8] lib/util.c:1869(is_myname)
is_myname("STELTER") returns 0
[2010/12/14 06:39:31.667707, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.667931, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.668250, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.668436, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.668599, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.669162, 10]
passdb/pdb_get_set.c:608(pdb_set_username)
pdb_set_username: setting username cstelter, was
[2010/12/14 06:39:31.669466, 10]
passdb/pdb_get_set.c:631(pdb_set_domain)
pdb_set_domain: setting domain STELTER, was
[2010/12/14 06:39:31.669647, 10]
passdb/pdb_get_set.c:654(pdb_set_nt_username)
pdb_set_nt_username: setting nt username , was
[2010/12/14 06:39:31.669841, 10]
passdb/pdb_get_set.c:677(pdb_set_fullname)
pdb_set_full_name: setting full name Craig Stelter, was
[2010/12/14 06:39:31.670203, 10]
passdb/pdb_get_set.c:770(pdb_set_homedir)
pdb_set_homedir: setting home dir \\fedorabox\cstelter\winprofile,
was
[2010/12/14 06:39:31.670428, 10]
passdb/pdb_get_set.c:746(pdb_set_dir_drive)
pdb_set_dir_drive: setting dir drive I:, was NULL
[2010/12/14 06:39:31.670614, 10]
passdb/pdb_get_set.c:700(pdb_set_logon_script)
pdb_set_logon_script: setting logon script logon.bat, was
[2010/12/14 06:39:31.670827, 10]
passdb/pdb_get_set.c:723(pdb_set_profile_path)
pdb_set_profile_path: setting profile path \\fedorabox\Profiles
\cstelter, was
[2010/12/14 06:39:31.671112, 10]
passdb/pdb_get_set.c:813(pdb_set_workstations)
pdb_set_workstations: setting workstations , was
[2010/12/14 06:39:31.671362, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/12/14 06:39:31.671565, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/12/14 06:39:31.671742, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/12/14 06:39:31.671915, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.672186, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.672594, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: password history, val: 0
[2010/12/14 06:39:31.672882, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.673233, 10]
passdb/pdb_get_set.c:537(pdb_set_user_sid)
pdb_set_user_sid: setting user sid
S-1-5-21-1265826603-3207742958-388371694-1001
[2010/12/14 06:39:31.673480, 10]
passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
pdb_set_user_sid_from_rid:
setting user sid S-1-5-21-1265826603-3207742958-388371694-1001 from
rid 1001
[2010/12/14 06:39:31.673810, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/12/14 06:39:31.674113, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/12/14 06:39:31.674311, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/12/14 06:39:31.674486, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.674649, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.675049, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: maximum password age, val: -1
[2010/12/14 06:39:31.675357, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.675693, 5] lib/username.c:133(Get_Pwnam_alloc)
Finding user cstelter
[2010/12/14 06:39:31.675912, 5] lib/username.c:77(Get_Pwnam_internals)
Trying _Get_Pwnam(), username as lowercase is cstelter
[2010/12/14 06:39:31.676609, 5] lib/username.c:110(Get_Pwnam_internals)
Get_Pwnam_internals did find user [cstelter]!
[2010/12/14 06:39:31.677105, 10]
lib/gencache.c:334(gencache_get_data_blob)
Cache entry with key = IDMAP/GID2SID/503 couldn't be found
[2010/12/14 06:39:31.696783, 5] passdb/lookup_sid.c:1387(gid_to_sid)
gid_to_sid: winbind failed to find a sid for gid 503
[2010/12/14 06:39:31.697231, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/12/14 06:39:31.697443, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/12/14 06:39:31.697621, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/12/14 06:39:31.697798, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.698038, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.698800, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.699750, 10]
passdb/lookup_sid.c:1182(legacy_gid_to_sid)
LEGACY: gid 503 -> sid S-1-22-2-503
[2010/12/14 06:39:31.700428, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/12/14 06:39:31.700720, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/12/14 06:39:31.700903, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/12/14 06:39:31.701219, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.701405, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.701779, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: password history, val: 0
[2010/12/14 06:39:31.702149, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.702437, 10]
passdb/pdb_get_set.c:608(pdb_set_username)
pdb_set_username: setting username cstelter, was
[2010/12/14 06:39:31.702646, 10]
passdb/pdb_get_set.c:631(pdb_set_domain)
pdb_set_domain: setting domain STELTER, was
[2010/12/14 06:39:31.702821, 10]
passdb/pdb_get_set.c:654(pdb_set_nt_username)
pdb_set_nt_username: setting nt username , was
[2010/12/14 06:39:31.703168, 10]
passdb/pdb_get_set.c:677(pdb_set_fullname)
pdb_set_full_name: setting full name Craig Stelter, was
[2010/12/14 06:39:31.703441, 10]
passdb/pdb_get_set.c:770(pdb_set_homedir)
pdb_set_homedir: setting home dir \\fedorabox\cstelter\winprofile,
was
[2010/12/14 06:39:31.703643, 10]
passdb/pdb_get_set.c:746(pdb_set_dir_drive)
pdb_set_dir_drive: setting dir drive I:, was NULL
[2010/12/14 06:39:31.703836, 10]
passdb/pdb_get_set.c:700(pdb_set_logon_script)
pdb_set_logon_script: setting logon script logon.bat, was
[2010/12/14 06:39:31.704169, 10]
passdb/pdb_get_set.c:723(pdb_set_profile_path)
pdb_set_profile_path: setting profile path \\fedorabox\Profiles
\cstelter, was
[2010/12/14 06:39:31.704396, 10]
passdb/pdb_get_set.c:813(pdb_set_workstations)
pdb_set_workstations: setting workstations , was
[2010/12/14 06:39:31.704602, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/12/14 06:39:31.704792, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/12/14 06:39:31.705031, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/12/14 06:39:31.705241, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.705405, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.705758, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: password history, val: 0
[2010/12/14 06:39:31.706247, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.706488, 10]
passdb/pdb_get_set.c:537(pdb_set_user_sid)
pdb_set_user_sid: setting user sid
S-1-5-21-1265826603-3207742958-388371694-1001
[2010/12/14 06:39:31.706691, 10]
passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
pdb_set_user_sid_from_rid:
setting user sid S-1-5-21-1265826603-3207742958-388371694-1001 from
rid 1001
[2010/12/14 06:39:31.707242, 10]
lib/gencache.c:345(gencache_get_data_blob)
Returning valid cache entry: key =
IDMAP/SID2GID/S-1-5-21-1265826603-3207742958-388371694-513, value =
16777216, timeout = Sat Dec 18 04:23:50 2010
[2010/12/14 06:39:31.707642, 10] passdb/lookup_sid.c:1518(sid_to_gid)
sid S-1-5-21-1265826603-3207742958-388371694-513 -> gid 16777216
[2010/12/14 06:39:31.707881, 10]
passdb/pdb_get_set.c:595(pdb_set_group_sid)
pdb_set_group_sid: setting group sid
S-1-5-21-1265826603-3207742958-388371694-513
[2010/12/14 06:39:31.708261, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.708501, 9]
passdb/passdb.c:2182(pdb_update_autolock_flag)
pdb_update_autolock_flag: Account cstelter not autolocked, no check
needed
[2010/12/14 06:39:31.708721,
4] ../libcli/auth/ntlm_check.c:399(ntlm_password_check)
ntlm_password_check: Checking NT MD4 password
[2010/12/14 06:39:31.709358, 4] auth/auth_sam.c:180(sam_account_ok)
sam_account_ok: Checking SMB password for user cstelter
[2010/12/14 06:39:31.709712, 5] auth/auth_sam.c:162(logon_hours_ok)
logon_hours_ok: user cstelter allowed to logon at this time (Tue Dec
14 12:39:31 2010
)
[2010/12/14 06:39:31.709990, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.710178, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.710336, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.710512, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.710678, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.711105, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: maximum password age, val: -1
[2010/12/14 06:39:31.711415, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.711652, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.711838, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.712098, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.712293, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.712460, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.713332, 10] lib/system_smbd.c:122(sys_getgrouplist)
sys_getgrouplist: user [cstelter]
[2010/12/14 06:39:31.714543, 10]
lib/gencache.c:334(gencache_get_data_blob)
Cache entry with key = IDMAP/GID2SID/502 couldn't be found
[2010/12/14 06:39:31.724495, 5] passdb/lookup_sid.c:1387(gid_to_sid)
gid_to_sid: winbind failed to find a sid for gid 502
[2010/12/14 06:39:31.724815, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/12/14 06:39:31.725089, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/12/14 06:39:31.725276, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/12/14 06:39:31.725449, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.725604, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.726422, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.726668, 10]
passdb/lookup_sid.c:1182(legacy_gid_to_sid)
LEGACY: gid 502 -> sid S-1-22-2-502
[2010/12/14 06:39:31.726841, 5]
auth/auth_util.c:649(make_server_info_sam)
make_server_info_sam: made server info for user cstelter -> cstelter
[2010/12/14 06:39:31.727409, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.727686, 3] auth/auth.c:265(check_ntlm_password)
check_ntlm_password: sam authentication for user [cstelter] succeeded
[2010/12/14 06:39:31.727888, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.728203, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.728404, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.728586, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.728757, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.729167, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.729409, 5] auth/auth.c:291(check_ntlm_password)
check_ntlm_password: PAM Account for user [cstelter] succeeded
[2010/12/14 06:39:31.729588, 2] auth/auth.c:304(check_ntlm_password)
check_ntlm_password: authentication for user [cstelter] -> [cstelter]
-> [cstelter] succeeded
[2010/12/14 06:39:31.729787, 5] auth/auth_util.c:2119(free_user_info)
attempting to free (and zero) a user_info structure
[2010/12/14 06:39:31.730050, 10] auth/auth_util.c:2123(free_user_info)
structure was created for cstelter
[2010/12/14 06:39:31.730282, 10]
auth/token_util.c:356(create_local_nt_token)
Create local NT token for
S-1-5-21-1265826603-3207742958-388371694-1001
[2010/12/14 06:39:31.730548, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.730748, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.730929, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.731224, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.731398, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.733578, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.734118, 3] lib/privileges.c:63(get_privileges)
get_privileges: No privileges assigned to SID
[S-1-5-21-1265826603-3207742958-388371694-1001]
[2010/12/14 06:39:31.734479, 3] lib/privileges.c:63(get_privileges)
get_privileges: No privileges assigned to SID [S-1-22-2-503]
[2010/12/14 06:39:31.734773, 5]
lib/privileges.c:128(get_privileges_for_sids)
get_privileges_for_sids: sid = S-1-1-0
Privilege set:
SE_PRIV 0x0 0x0 0x0 0x0
[2010/12/14 06:39:31.735269, 3] lib/privileges.c:63(get_privileges)
get_privileges: No privileges assigned to SID [S-1-5-2]
[2010/12/14 06:39:31.735568, 3] lib/privileges.c:63(get_privileges)
get_privileges: No privileges assigned to SID [S-1-5-11]
[2010/12/14 06:39:31.735853, 3] lib/privileges.c:63(get_privileges)
get_privileges: No privileges assigned to SID [S-1-22-2-502]
[2010/12/14 06:39:31.736274, 10]
lib/gencache.c:334(gencache_get_data_blob)
Cache entry with key = IDMAP/SID2GID/S-1-1-0 couldn't be found
[2010/12/14 06:39:31.740170, 10] passdb/lookup_sid.c:1511(sid_to_gid)
winbind failed to find a gid for sid S-1-1-0
[2010/12/14 06:39:31.740498, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.740696, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.741054, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.741300, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.741478, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.742257, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.742570, 10]
passdb/lookup_sid.c:1256(legacy_sid_to_gid)
LEGACY: mapping failed for sid S-1-1-0
[2010/12/14 06:39:31.742770, 10]
auth/auth_util.c:753(create_local_token)
Could not convert SID S-1-1-0 to gid, ignoring it
[2010/12/14 06:39:31.743170, 10]
lib/gencache.c:334(gencache_get_data_blob)
Cache entry with key = IDMAP/SID2GID/S-1-5-2 couldn't be found
[2010/12/14 06:39:31.747064, 10] passdb/lookup_sid.c:1511(sid_to_gid)
winbind failed to find a gid for sid S-1-5-2
[2010/12/14 06:39:31.747427, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.747618, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.747798, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.748054, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.748248, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.748921, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.749317, 10]
passdb/lookup_sid.c:1256(legacy_sid_to_gid)
LEGACY: mapping failed for sid S-1-5-2
[2010/12/14 06:39:31.749518, 10]
auth/auth_util.c:753(create_local_token)
Could not convert SID S-1-5-2 to gid, ignoring it
[2010/12/14 06:39:31.749833, 10]
lib/gencache.c:334(gencache_get_data_blob)
Cache entry with key = IDMAP/SID2GID/S-1-5-11 couldn't be found
[2010/12/14 06:39:31.753808, 10] passdb/lookup_sid.c:1511(sid_to_gid)
winbind failed to find a gid for sid S-1-5-11
[2010/12/14 06:39:31.754249, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.754457, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.754635, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.754814, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.755055, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.755740, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.756134, 10]
passdb/lookup_sid.c:1256(legacy_sid_to_gid)
LEGACY: mapping failed for sid S-1-5-11
[2010/12/14 06:39:31.756348, 10]
auth/auth_util.c:753(create_local_token)
Could not convert SID S-1-5-11 to gid, ignoring it
[2010/12/14 06:39:31.756675, 10]
auth/token_util.c:531(debug_nt_user_token)
NT user token of user S-1-5-21-1265826603-3207742958-388371694-1001
contains 7 SIDs
SID[ 0]: S-1-5-21-1265826603-3207742958-388371694-1001
SID[ 1]: S-1-22-2-503
SID[ 2]: S-1-1-0
SID[ 3]: S-1-5-2
SID[ 4]: S-1-5-11
SID[ 5]: S-1-22-2-502
SID[ 6]: S-1-22-1-500
SE_PRIV 0x0 0x0 0x0 0x0
[2010/12/14 06:39:31.757661, 10]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 500
Primary group is 503 and contains 2 supplementary groups
Group[ 0]: 503
Group[ 1]: 502
[2010/12/14 06:39:31.758149, 10]
auth/auth_ntlmssp.c:139(auth_ntlmssp_check_password)
Got NT session key of length 16
[2010/12/14 06:39:31.758387, 10]
libsmb/ntlmssp.c:815(ntlmssp_server_auth)
ntlmssp_server_auth: Created NTLM2 session key.
[2010/12/14 06:39:31.758637, 3]
libsmb/ntlmssp_sign.c:343(ntlmssp_sign_init)
NTLMSSP Sign/Seal - Initialising with flags:
[2010/12/14 06:39:31.758821, 3]
libsmb/ntlmssp.c:65(debug_ntlmssp_flags)
Got NTLMSSP neg_flags=0x60088215
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
[2010/12/14 06:39:31.759649, 10]
smbd/password.c:278(register_existing_vuid)
register_existing_vuid: (500,503) cstelter cstelter STELTER guest=0
[2010/12/14 06:39:31.759890, 3]
smbd/password.c:282(register_existing_vuid)
register_existing_vuid: User name: cstelter Real name: Craig Stelter
[2010/12/14 06:39:31.763728, 3]
smbd/password.c:292(register_existing_vuid)
register_existing_vuid: UNIX uid 500 is UNIX user cstelter, and will
be vuid 100
[2010/12/14 06:39:31.764162, 10]
lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
Locking key 49442F31323637382F31
[2010/12/14 06:39:31.764476, 10]
lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
Allocated locked data 0x0x1487990
[2010/12/14 06:39:31.765307, 10]
lib/dbwrap_tdb.c:42(db_tdb_record_destr)
Unlocking key 49442F31323637382F31
[2010/12/14 06:39:31.765743, 7] param/loadparm.c:9399(lp_servicenumber)
lp_servicenumber: couldn't find cstelter
[2010/12/14 06:39:31.766086, 3]
smbd/password.c:223(register_homes_share)
Adding homes service for user 'cstelter' using home directory:
'/home/cstelter'
[2010/12/14 06:39:31.791882, 8] param/loadparm.c:6163(add_a_service)
add_a_service: Creating snum = 7 for cstelter
[2010/12/14 06:39:31.792501, 10] param/loadparm.c:6210(hash_a_service)
hash_a_service: hashing index 7 for service name cstelter
[2010/12/14 06:39:31.792722, 3] param/loadparm.c:6265(lp_add_home)
adding home's share [cstelter] for user 'cstelter' at '/home/cstelter'
[2010/12/14 06:39:31.793195, 6]
param/loadparm.c:7133(lp_file_list_changed)
lp_file_list_changed()
file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Tue
Dec 14 05:28:19 2010
[2010/12/14 06:39:31.793659, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:31.793804, 5] lib/util.c:627(show_msg)
size=118
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=136
smb_flg2=51203
smb_tid=0
smb_pid=12676
smb_uid=100
smb_mid=4
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=75
[2010/12/14 06:39:31.794737, 10] ../lib/util/util.c:278(_dump_data)
[0000] A1 07 30 05 A0 03 0A 01 00 55 00 6E 00 69 00
78 ..0..... .U.n.i.x
[0010] 00 00 00 53 00 61 00 6D 00 62 00 61 00 20 00
33 ...S.a.m .b.a. .3
[0020] 00 2E 00 35 00 2E 00 36 00 2D 00 37 00 30 00
2E ...5...6 .-.7.0..
[0030] 00 66 00 63 00 31 00 34 00 00 00 53 00 54 00
45 .f.c.1.4 ...S.T.E
[0040] 00 4C 00 54 00 45 00 52 00 00 00 .L.T.E.R ...
[2010/12/14 06:39:31.800683, 10]
lib/util_sock.c:726(read_smb_length_return_keepalive)
got smb length of 82
[2010/12/14 06:39:31.801231, 6] smbd/process.c:1482(process_smb)
got message type 0x0 of len 0x52
[2010/12/14 06:39:31.801440, 3] smbd/process.c:1485(process_smb)
Transaction 3 of length 86 (0 toread)
[2010/12/14 06:39:31.801600, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:31.801711, 5] lib/util.c:627(show_msg)
size=82
smb_com=0x75
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=8
smb_flg2=51201
smb_tid=65535
smb_pid=12676
smb_uid=100
smb_mid=5
smt_wct=4
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
smb_vwv[ 2]= 8 (0x8)
smb_vwv[ 3]= 1 (0x1)
smb_bcc=39
[2010/12/14 06:39:31.802574, 10] ../lib/util/util.c:278(_dump_data)
[0000] 00 5C 00 5C 00 46 00 45 00 44 00 4F 00 52 00
41 .\.\.F.E .D.O.R.A
[0010] 00 42 00 4F 00 58 00 5C 00 49 00 50 00 43 00
24 .B.O.X.\ .I.P.C.$
[0020] 00 00 00 49 50 43 00 ...IPC.
[2010/12/14 06:39:31.803099, 3] smbd/process.c:1294(switch_message)
switch message SMBtconX (pid 12678) conn 0x0
[2010/12/14 06:39:31.803299, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.803476, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.803636, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.803926, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:31.804302, 4] smbd/reply.c:786(reply_tcon_and_X)
Client requested device type [IPC] for share [IPC$]
[2010/12/14 06:39:31.804596, 5] smbd/service.c:1227(make_connection)
making a connection to 'normal' service ipc$
[2010/12/14 06:39:31.804889, 10] smbd/share_access.c:238(user_ok_token)
user_ok_token: share IPC$ is ok for unix user cstelter
[2010/12/14 06:39:31.805329, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.805564, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.805726, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.805883, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.806143, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.806528, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: maximum password age, val: -1
[2010/12/14 06:39:31.806922, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.807260, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.807450, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.807619, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.807786, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.816083, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.816735, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: password history, val: 0
[2010/12/14 06:39:31.817105, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.817408, 10]
passdb/pdb_get_set.c:608(pdb_set_username)
pdb_set_username: setting username cstelter, was
[2010/12/14 06:39:31.817618, 10]
passdb/pdb_get_set.c:631(pdb_set_domain)
pdb_set_domain: setting domain STELTER, was
[2010/12/14 06:39:31.817786, 10]
passdb/pdb_get_set.c:654(pdb_set_nt_username)
pdb_set_nt_username: setting nt username , was
[2010/12/14 06:39:31.818018, 10]
passdb/pdb_get_set.c:677(pdb_set_fullname)
pdb_set_full_name: setting full name Craig Stelter, was
[2010/12/14 06:39:31.818289, 10]
passdb/pdb_get_set.c:770(pdb_set_homedir)
pdb_set_homedir: setting home dir \\fedorabox\cstelter\winprofile,
was
[2010/12/14 06:39:31.818485, 10]
passdb/pdb_get_set.c:746(pdb_set_dir_drive)
pdb_set_dir_drive: setting dir drive I:, was NULL
[2010/12/14 06:39:31.818667, 10]
passdb/pdb_get_set.c:700(pdb_set_logon_script)
pdb_set_logon_script: setting logon script logon.bat, was
[2010/12/14 06:39:31.818882, 10]
passdb/pdb_get_set.c:723(pdb_set_profile_path)
pdb_set_profile_path: setting profile path \\fedorabox\Profiles
\cstelter, was
[2010/12/14 06:39:31.819202, 10]
passdb/pdb_get_set.c:813(pdb_set_workstations)
pdb_set_workstations: setting workstations , was
[2010/12/14 06:39:31.819418, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.819606, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.819772, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.825694, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.826181, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.826592, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: password history, val: 0
[2010/12/14 06:39:31.826803, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.827010, 10]
passdb/pdb_get_set.c:537(pdb_set_user_sid)
pdb_set_user_sid: setting user sid
S-1-5-21-1265826603-3207742958-388371694-1001
[2010/12/14 06:39:31.827210, 10]
passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
pdb_set_user_sid_from_rid:
setting user sid S-1-5-21-1265826603-3207742958-388371694-1001 from
rid 1001
[2010/12/14 06:39:31.827476, 10]
passdb/pdb_get_set.c:595(pdb_set_group_sid)
pdb_set_group_sid: setting group sid
S-1-5-21-1265826603-3207742958-388371694-513
[2010/12/14 06:39:31.827698, 5] lib/username.c:133(Get_Pwnam_alloc)
Finding user cstelter
[2010/12/14 06:39:31.827882, 5] lib/username.c:77(Get_Pwnam_internals)
Trying _Get_Pwnam(), username as lowercase is cstelter
[2010/12/14 06:39:31.828177, 5] lib/username.c:110(Get_Pwnam_internals)
Get_Pwnam_internals did find user [cstelter]!
[2010/12/14 06:39:31.828476, 10]
smbd/service.c:163(set_conn_connectpath)
set_conn_connectpath: service IPC$, connectpath = /tmp
[2010/12/14 06:39:31.828813, 3]
smbd/service.c:807(make_connection_snum)
Connect path is '/tmp' for service [IPC$]
[2010/12/14 06:39:31.836292, 10] lib/util_seaccess.c:58(se_map_generic)
se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
[2010/12/14 06:39:31.836669, 3] smbd/vfs.c:97(vfs_init_default)
Initialising default vfs hooks
[2010/12/14 06:39:31.836894, 10] smbd/vfs.c:48(vfs_find_backend_entry)
vfs_find_backend_entry called for /[Default VFS]/
[2010/12/14 06:39:31.837169, 5] smbd/vfs.c:87(smb_register_vfs)
Successfully added vfs backend '/[Default VFS]/'
[2010/12/14 06:39:31.837377, 10] smbd/vfs.c:48(vfs_find_backend_entry)
vfs_find_backend_entry called for posixacl
[2010/12/14 06:39:31.837561, 5] smbd/vfs.c:87(smb_register_vfs)
Successfully added vfs backend 'posixacl'
[2010/12/14 06:39:31.837727, 3] smbd/vfs.c:122(vfs_init_custom)
Initialising custom vfs hooks from [/[Default VFS]/]
[2010/12/14 06:39:31.837901, 10] smbd/vfs.c:48(vfs_find_backend_entry)
vfs_find_backend_entry called for /[Default VFS]/
Successfully loaded vfs module [/[Default VFS]/] with the new modules
system
[2010/12/14 06:39:31.838232, 5] smbd/connection.c:142(claim_connection)
claiming [IPC$]
[2010/12/14 06:39:31.838780, 10]
lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
Locking key 86310000FFFFFFFF0100
[2010/12/14 06:39:31.843508, 10]
lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
Allocated locked data 0x0x149fe60
[2010/12/14 06:39:31.844198, 10]
lib/dbwrap_tdb.c:42(db_tdb_record_destr)
Unlocking key 86310000FFFFFFFF0100
[2010/12/14 06:39:31.845087, 10]
smbd/service.c:163(set_conn_connectpath)
set_conn_connectpath: service IPC$, connectpath = /tmp
[2010/12/14 06:39:31.845403, 10] smbd/share_access.c:238(user_ok_token)
user_ok_token: share IPC$ is ok for unix user cstelter
[2010/12/14 06:39:31.845604, 10]
smbd/share_access.c:283(is_share_read_only_for_token)
is_share_read_only_for_user: share IPC$ is read-only for unix user
cstelter
[2010/12/14 06:39:31.845878, 10] lib/util_seaccess.c:58(se_map_generic)
se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
[2010/12/14 06:39:31.846270, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.846513, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.846679, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.846840, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.851301, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.851810, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: maximum password age, val: -1
[2010/12/14 06:39:31.852212, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.852461, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.852653, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.852826, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.853103, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.853284, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.853619, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: password history, val: 0
[2010/12/14 06:39:31.853893, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.858502, 10]
passdb/pdb_get_set.c:608(pdb_set_username)
pdb_set_username: setting username cstelter, was
[2010/12/14 06:39:31.858767, 10]
passdb/pdb_get_set.c:631(pdb_set_domain)
pdb_set_domain: setting domain STELTER, was
[2010/12/14 06:39:31.858997, 10]
passdb/pdb_get_set.c:654(pdb_set_nt_username)
pdb_set_nt_username: setting nt username , was
[2010/12/14 06:39:31.859347, 10]
passdb/pdb_get_set.c:677(pdb_set_fullname)
pdb_set_full_name: setting full name Craig Stelter, was
[2010/12/14 06:39:31.859620, 10]
passdb/pdb_get_set.c:770(pdb_set_homedir)
pdb_set_homedir: setting home dir \\fedorabox\cstelter\winprofile,
was
[2010/12/14 06:39:31.859820, 10]
passdb/pdb_get_set.c:746(pdb_set_dir_drive)
pdb_set_dir_drive: setting dir drive I:, was NULL
[2010/12/14 06:39:31.860085, 10]
passdb/pdb_get_set.c:700(pdb_set_logon_script)
pdb_set_logon_script: setting logon script logon.bat, was
[2010/12/14 06:39:31.860269, 10]
passdb/pdb_get_set.c:723(pdb_set_profile_path)
pdb_set_profile_path: setting profile path \\fedorabox\Profiles
\cstelter, was
[2010/12/14 06:39:31.860426, 10]
passdb/pdb_get_set.c:813(pdb_set_workstations)
pdb_set_workstations: setting workstations , was
[2010/12/14 06:39:31.860619, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.860807, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.869280, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.869567, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.869737, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.870196, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: password history, val: 0
[2010/12/14 06:39:31.870506, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.870736, 10]
passdb/pdb_get_set.c:537(pdb_set_user_sid)
pdb_set_user_sid: setting user sid
S-1-5-21-1265826603-3207742958-388371694-1001
[2010/12/14 06:39:31.870931, 10]
passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
pdb_set_user_sid_from_rid:
setting user sid S-1-5-21-1265826603-3207742958-388371694-1001 from
rid 1001
[2010/12/14 06:39:31.871349, 10]
passdb/pdb_get_set.c:595(pdb_set_group_sid)
pdb_set_group_sid: setting group sid
S-1-5-21-1265826603-3207742958-388371694-513
[2010/12/14 06:39:31.871637, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (500, 503) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.871852, 5]
auth/token_util.c:531(debug_nt_user_token)
NT user token of user S-1-5-21-1265826603-3207742958-388371694-1001
contains 7 SIDs
SID[ 0]: S-1-5-21-1265826603-3207742958-388371694-1001
SID[ 1]: S-1-22-2-503
SID[ 2]: S-1-1-0
SID[ 3]: S-1-5-2
SID[ 4]: S-1-5-11
SID[ 5]: S-1-22-2-502
SID[ 6]: S-1-22-1-500
SE_PRIV 0x0 0x0 0x0 0x0
[2010/12/14 06:39:31.872650, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 500
Primary group is 503 and contains 2 supplementary groups
Group[ 0]: 503
Group[ 1]: 502
[2010/12/14 06:39:31.873241, 5] smbd/uid.c:354(change_to_user)
change_to_user uid=(0,500) gid=(0,503)
[2010/12/14 06:39:31.873587, 10]
smbd/service.c:163(set_conn_connectpath)
set_conn_connectpath: service IPC$, connectpath = /tmp
[2010/12/14 06:39:31.873894, 10]
modules/vfs_default.c:152(vfswrap_fs_capabilities)
vfswrap_fs_capabilities: timestamp resolution of sec available on
share IPC$, directory /tmp
[2010/12/14 06:39:31.874221, 3]
smbd/service.c:1070(make_connection_snum)
fedorabox (::1) connect to service IPC$ initially as user cstelter
(uid=500, gid=503) (pid 12678)
[2010/12/14 06:39:31.874468, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.874649, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.874811, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.875183, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:31.875450, 3] smbd/reply.c:865(reply_tcon_and_X)
tconX service=IPC$
[2010/12/14 06:39:31.884412, 10]
lib/util_sock.c:726(read_smb_length_return_keepalive)
got smb length of 100
[2010/12/14 06:39:31.884855, 6] smbd/process.c:1482(process_smb)
got message type 0x0 of len 0x64
[2010/12/14 06:39:31.885153, 3] smbd/process.c:1485(process_smb)
Transaction 4 of length 104 (0 toread)
[2010/12/14 06:39:31.885346, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:31.885459, 5] lib/util.c:627(show_msg)
size=100
smb_com=0x32
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=8
smb_flg2=51201
smb_tid=1
smb_pid=12676
smb_uid=100
smb_mid=6
smt_wct=15
smb_vwv[ 0]= 32 (0x20)
smb_vwv[ 1]= 0 (0x0)
smb_vwv[ 2]= 2 (0x2)
smb_vwv[ 3]=16644 (0x4104)
smb_vwv[ 4]= 0 (0x0)
smb_vwv[ 5]= 0 (0x0)
smb_vwv[ 6]= 0 (0x0)
smb_vwv[ 7]= 0 (0x0)
smb_vwv[ 8]= 0 (0x0)
smb_vwv[ 9]= 32 (0x20)
smb_vwv[10]= 68 (0x44)
smb_vwv[11]= 0 (0x0)
smb_vwv[12]= 100 (0x64)
smb_vwv[13]= 1 (0x1)
smb_vwv[14]= 16 (0x10)
smb_bcc=35
[2010/12/14 06:39:31.886820, 10] ../lib/util/util.c:278(_dump_data)
[0000] 00 44 20 03 00 5C 00 66 00 65 00 64 00 6F 00
72 .D ..\.f .e.d.o.r
[0010] 00 61 00 62 00 6F 00 78 00 5C 00 73 00 79 00
73 .a.b.o.x .\.s.y.s
[0020] 00 00 00 ...
[2010/12/14 06:39:31.887606, 3] smbd/process.c:1294(switch_message)
switch message SMBtrans2 (pid 12678) conn 0x149a7f0
[2010/12/14 06:39:31.887853, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (500, 503) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.888140, 5]
auth/token_util.c:531(debug_nt_user_token)
NT user token of user S-1-5-21-1265826603-3207742958-388371694-1001
contains 7 SIDs
SID[ 0]: S-1-5-21-1265826603-3207742958-388371694-1001
SID[ 1]: S-1-22-2-503
SID[ 2]: S-1-1-0
SID[ 3]: S-1-5-2
SID[ 4]: S-1-5-11
SID[ 5]: S-1-22-2-502
SID[ 6]: S-1-22-1-500
SE_PRIV 0x0 0x0 0x0 0x0
[2010/12/14 06:39:31.888837, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 500
Primary group is 503 and contains 2 supplementary groups
Group[ 0]: 503
Group[ 1]: 502
[2010/12/14 06:39:31.893515, 5] smbd/uid.c:354(change_to_user)
change_to_user uid=(0,500) gid=(0,503)
[2010/12/14 06:39:31.893742, 4] smbd/vfs.c:721(vfs_ChDir)
vfs_ChDir to /tmp
[2010/12/14 06:39:31.894156, 10]
smbd/trans2.c:7996(call_trans2getdfsreferral)
call_trans2getdfsreferral
[2010/12/14 06:39:31.894490, 10] smbd/msdfs.c:107(parse_dfs_path)
parse_dfs_path: temp = |fedorabox\sys| after trimming \'s
[2010/12/14 06:39:31.894693, 10] smbd/msdfs.c:132(parse_dfs_path)
parse_dfs_path: hostname: fedorabox
[2010/12/14 06:39:31.894854, 10] smbd/msdfs.c:174(parse_dfs_path)
parse_dfs_path: servicename: sys
[2010/12/14 06:39:31.895142, 3] smbd/msdfs.c:848(get_referred_path)
get_referred_path: |sys| in dfs path \fedorabox\sys is not a dfs root.
[2010/12/14 06:39:31.895402, 3] smbd/error.c:80(error_packet_set)
error packet at smbd/trans2.c(8018) cmd=50 (SMBtrans2)
NT_STATUS_NOT_FOUND
[2010/12/14 06:39:31.895764, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:31.895928, 5] lib/util.c:627(show_msg)
size=35
smb_com=0x32
smb_rcls=37
smb_reh=2
smb_err=49152
smb_flg=136
smb_flg2=51203
smb_tid=1
smb_pid=12676
smb_uid=100
smb_mid=6
smt_wct=0
smb_bcc=0
[2010/12/14 06:39:31.899603, 10]
lib/util_sock.c:726(read_smb_length_return_keepalive)
got smb length of 35
[2010/12/14 06:39:31.902619, 6] smbd/process.c:1482(process_smb)
got message type 0x0 of len 0x23
[2010/12/14 06:39:31.903026, 3] smbd/process.c:1485(process_smb)
Transaction 5 of length 39 (0 toread)
[2010/12/14 06:39:31.903229, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:31.903349, 5] lib/util.c:627(show_msg)
size=35
smb_com=0x71
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=8
smb_flg2=51201
smb_tid=1
smb_pid=12676
smb_uid=100
smb_mid=7
smt_wct=0
smb_bcc=0
[2010/12/14 06:39:31.904043, 3] smbd/process.c:1294(switch_message)
switch message SMBtdis (pid 12678) conn 0x149a7f0
[2010/12/14 06:39:31.904255, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.904543, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.904727, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.905098, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:31.905418, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.905621, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.905786, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.906129, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:31.906352, 3] smbd/service.c:1251(close_cnum)
fedorabox (::1) closed connection to service IPC$
[2010/12/14 06:39:31.906576, 3] smbd/connection.c:31(yield_connection)
Yielding connection to IPC$
[2010/12/14 06:39:31.907095, 10]
lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
Locking key 86310000FFFFFFFF0100
[2010/12/14 06:39:31.907465, 10]
lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
Allocated locked data 0x0x148ebc0
[2010/12/14 06:39:31.907772, 10]
lib/dbwrap_tdb.c:42(db_tdb_record_destr)
Unlocking key 86310000FFFFFFFF0100
[2010/12/14 06:39:31.908410, 4] smbd/vfs.c:721(vfs_ChDir)
vfs_ChDir to /
[2010/12/14 06:39:31.908663, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.908841, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.909074, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.909354, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:31.909629, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:31.909781, 5] lib/util.c:627(show_msg)
size=35
smb_com=0x71
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=136
smb_flg2=51203
smb_tid=1
smb_pid=12676
smb_uid=100
smb_mid=7
smt_wct=0
smb_bcc=0
[2010/12/14 06:39:31.911874, 10]
lib/util_sock.c:726(read_smb_length_return_keepalive)
got smb length of 82
[2010/12/14 06:39:31.912375, 6] smbd/process.c:1482(process_smb)
got message type 0x0 of len 0x52
[2010/12/14 06:39:31.912579, 3] smbd/process.c:1485(process_smb)
Transaction 6 of length 86 (0 toread)
[2010/12/14 06:39:31.912744, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:31.912853, 5] lib/util.c:627(show_msg)
size=82
smb_com=0x75
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=8
smb_flg2=51201
smb_tid=65535
smb_pid=12676
smb_uid=100
smb_mid=8
smt_wct=4
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
smb_vwv[ 2]= 8 (0x8)
smb_vwv[ 3]= 1 (0x1)
smb_bcc=39
[2010/12/14 06:39:31.913748, 10] ../lib/util/util.c:278(_dump_data)
[0000] 00 5C 00 5C 00 46 00 45 00 44 00 4F 00 52 00
41 .\.\.F.E .D.O.R.A
[0010] 00 42 00 4F 00 58 00 5C 00 53 00 59 00 53 00
00 .B.O.X.\ .S.Y.S..
[0020] 00 3F 3F 3F 3F 3F 00 .?????.
[2010/12/14 06:39:31.914298, 3] smbd/process.c:1294(switch_message)
switch message SMBtconX (pid 12678) conn 0x0
[2010/12/14 06:39:31.914498, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.914674, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.914835, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.915189, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:31.915468, 4] smbd/reply.c:786(reply_tcon_and_X)
Client requested device type [?????] for share [SYS]
[2010/12/14 06:39:31.915735, 5] smbd/service.c:1227(make_connection)
making a connection to 'normal' service sys
[2010/12/14 06:39:31.916227, 10] smbd/share_access.c:238(user_ok_token)
user_ok_token: share sys is ok for unix user cstelter
[2010/12/14 06:39:31.916557, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.916776, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.917025, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.917239, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.917404, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.917768, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: maximum password age, val: -1
[2010/12/14 06:39:31.918152, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.918391, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.918574, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.918745, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.918911, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.919182, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.919539, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: password history, val: 0
[2010/12/14 06:39:31.919819, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.920172, 10]
passdb/pdb_get_set.c:608(pdb_set_username)
pdb_set_username: setting username cstelter, was
[2010/12/14 06:39:31.920392, 10]
passdb/pdb_get_set.c:631(pdb_set_domain)
pdb_set_domain: setting domain STELTER, was
[2010/12/14 06:39:31.920565, 10]
passdb/pdb_get_set.c:654(pdb_set_nt_username)
pdb_set_nt_username: setting nt username , was
[2010/12/14 06:39:31.920737, 10]
passdb/pdb_get_set.c:677(pdb_set_fullname)
pdb_set_full_name: setting full name Craig Stelter, was
[2010/12/14 06:39:31.921041, 10]
passdb/pdb_get_set.c:770(pdb_set_homedir)
pdb_set_homedir: setting home dir \\fedorabox\cstelter\winprofile,
was
[2010/12/14 06:39:31.921291, 10]
passdb/pdb_get_set.c:746(pdb_set_dir_drive)
pdb_set_dir_drive: setting dir drive I:, was NULL
[2010/12/14 06:39:31.921481, 10]
passdb/pdb_get_set.c:700(pdb_set_logon_script)
pdb_set_logon_script: setting logon script logon.bat, was
[2010/12/14 06:39:31.921694, 10]
passdb/pdb_get_set.c:723(pdb_set_profile_path)
pdb_set_profile_path: setting profile path \\fedorabox\Profiles
\cstelter, was
[2010/12/14 06:39:31.921894, 10]
passdb/pdb_get_set.c:813(pdb_set_workstations)
pdb_set_workstations: setting workstations , was
[2010/12/14 06:39:31.922206, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.922414, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.922582, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.922749, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.922910, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.923361, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: password history, val: 0
[2010/12/14 06:39:31.923646, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.923866, 10]
passdb/pdb_get_set.c:537(pdb_set_user_sid)
pdb_set_user_sid: setting user sid
S-1-5-21-1265826603-3207742958-388371694-1001
[2010/12/14 06:39:31.924149, 10]
passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
pdb_set_user_sid_from_rid:
setting user sid S-1-5-21-1265826603-3207742958-388371694-1001 from
rid 1001
[2010/12/14 06:39:31.924552, 10]
passdb/pdb_get_set.c:595(pdb_set_group_sid)
pdb_set_group_sid: setting group sid
S-1-5-21-1265826603-3207742958-388371694-513
[2010/12/14 06:39:31.924796, 5] lib/username.c:133(Get_Pwnam_alloc)
Finding user cstelter
[2010/12/14 06:39:31.925064, 5] lib/username.c:77(Get_Pwnam_internals)
Trying _Get_Pwnam(), username as lowercase is cstelter
[2010/12/14 06:39:31.925270, 5] lib/username.c:110(Get_Pwnam_internals)
Get_Pwnam_internals did find user [cstelter]!
[2010/12/14 06:39:31.925569, 10]
smbd/service.c:163(set_conn_connectpath)
set_conn_connectpath: service sys, connectpath = /share/system
[2010/12/14 06:39:31.925805, 3]
smbd/service.c:807(make_connection_snum)
Connect path is '/share/system' for service [sys]
[2010/12/14 06:39:31.926209, 10] lib/util_seaccess.c:58(se_map_generic)
se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
[2010/12/14 06:39:31.926471, 3] smbd/vfs.c:97(vfs_init_default)
Initialising default vfs hooks
[2010/12/14 06:39:31.926656, 3] smbd/vfs.c:122(vfs_init_custom)
Initialising custom vfs hooks from [/[Default VFS]/]
[2010/12/14 06:39:31.926823, 10] smbd/vfs.c:48(vfs_find_backend_entry)
vfs_find_backend_entry called for /[Default VFS]/
Successfully loaded vfs module [/[Default VFS]/] with the new modules
system
[2010/12/14 06:39:31.929121, 5] smbd/connection.c:142(claim_connection)
claiming [sys]
[2010/12/14 06:39:31.929734, 10]
lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
Locking key 86310000FFFFFFFF0100
[2010/12/14 06:39:31.930051, 10]
lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
Allocated locked data 0x0x149fe60
[2010/12/14 06:39:31.930430, 10]
lib/dbwrap_tdb.c:42(db_tdb_record_destr)
Unlocking key 86310000FFFFFFFF0100
[2010/12/14 06:39:31.931109, 10]
smbd/service.c:163(set_conn_connectpath)
set_conn_connectpath: service sys, connectpath = /share/system
[2010/12/14 06:39:31.931380, 10] smbd/share_access.c:238(user_ok_token)
user_ok_token: share sys is ok for unix user cstelter
[2010/12/14 06:39:31.931609, 3] lib/util_sid.c:228(string_to_sid)
string_to_sid: Sid @stelters does not start with 'S-'.
[2010/12/14 06:39:31.935132, 5] smbd/password.c:423(user_in_netgroup)
Unable to get default yp domain, let's try without specifying it
[2010/12/14 06:39:31.935537, 5] smbd/password.c:430(user_in_netgroup)
looking for user cstelter of domain (ANY) in netgroup stelters
[2010/12/14 06:39:31.948718, 10] passdb/lookup_sid.c:69(lookup_name)
lookup_name: STELTER\stelters => STELTER (domain), stelters (name)
[2010/12/14 06:39:31.949161, 10] passdb/lookup_sid.c:70(lookup_name)
lookup_name: flags = 0x077
[2010/12/14 06:39:31.949377, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.949576, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.949745, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.949913, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.950173, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.950748, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.951491, 10] passdb/lookup_sid.c:69(lookup_name)
lookup_name: Unix Group\stelters => Unix Group (domain), stelters
(name)
[2010/12/14 06:39:31.951719, 10] passdb/lookup_sid.c:70(lookup_name)
lookup_name: flags = 0x077
[2010/12/14 06:39:31.966351, 10]
smbd/share_access.c:283(is_share_read_only_for_token)
is_share_read_only_for_user: share sys is read-write for unix user
cstelter
[2010/12/14 06:39:31.966840, 10] lib/util_seaccess.c:58(se_map_generic)
se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
[2010/12/14 06:39:31.967280, 10] lib/util_seaccess.c:58(se_map_generic)
se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
[2010/12/14 06:39:31.967724, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.968281, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.968537, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.968710, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.968871, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.969342, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: maximum password age, val: -1
[2010/12/14 06:39:31.969632, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.969865, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.970166, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.970358, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.970525, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.970689, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.971084, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: password history, val: 0
[2010/12/14 06:39:31.971396, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.971669, 10]
passdb/pdb_get_set.c:608(pdb_set_username)
pdb_set_username: setting username cstelter, was
[2010/12/14 06:39:31.971882, 10]
passdb/pdb_get_set.c:631(pdb_set_domain)
pdb_set_domain: setting domain STELTER, was
[2010/12/14 06:39:31.972192, 10]
passdb/pdb_get_set.c:654(pdb_set_nt_username)
pdb_set_nt_username: setting nt username , was
[2010/12/14 06:39:31.972387, 10]
passdb/pdb_get_set.c:677(pdb_set_fullname)
pdb_set_full_name: setting full name Craig Stelter, was
[2010/12/14 06:39:31.972630, 10]
passdb/pdb_get_set.c:770(pdb_set_homedir)
pdb_set_homedir: setting home dir \\fedorabox\cstelter\winprofile,
was
[2010/12/14 06:39:31.972825, 10]
passdb/pdb_get_set.c:746(pdb_set_dir_drive)
pdb_set_dir_drive: setting dir drive I:, was NULL
[2010/12/14 06:39:31.973099, 10]
passdb/pdb_get_set.c:700(pdb_set_logon_script)
pdb_set_logon_script: setting logon script logon.bat, was
[2010/12/14 06:39:31.973339, 10]
passdb/pdb_get_set.c:723(pdb_set_profile_path)
pdb_set_profile_path: setting profile path \\fedorabox\Profiles
\cstelter, was
[2010/12/14 06:39:31.973543, 10]
passdb/pdb_get_set.c:813(pdb_set_workstations)
pdb_set_workstations: setting workstations , was
[2010/12/14 06:39:31.973741, 3] smbd/sec_ctx.c:210(push_sec_ctx)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.973927, 3] smbd/uid.c:429(push_conn_ctx)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/12/14 06:39:31.974203, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/12/14 06:39:31.974378, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.974545, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.974901, 10]
lib/account_pol.c:333(account_policy_get)
account_policy_get: name: password history, val: 0
[2010/12/14 06:39:31.975288, 3] smbd/sec_ctx.c:418(pop_sec_ctx)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.975518, 10]
passdb/pdb_get_set.c:537(pdb_set_user_sid)
pdb_set_user_sid: setting user sid
S-1-5-21-1265826603-3207742958-388371694-1001
[2010/12/14 06:39:31.975724, 10]
passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid)
pdb_set_user_sid_from_rid:
setting user sid S-1-5-21-1265826603-3207742958-388371694-1001 from
rid 1001
[2010/12/14 06:39:31.976202, 10]
passdb/pdb_get_set.c:595(pdb_set_group_sid)
pdb_set_group_sid: setting group sid
S-1-5-21-1265826603-3207742958-388371694-513
[2010/12/14 06:39:31.976552, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (500, 503) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.976775, 5]
auth/token_util.c:531(debug_nt_user_token)
NT user token of user S-1-5-21-1265826603-3207742958-388371694-1001
contains 7 SIDs
SID[ 0]: S-1-5-21-1265826603-3207742958-388371694-1001
SID[ 1]: S-1-22-2-503
SID[ 2]: S-1-1-0
SID[ 3]: S-1-5-2
SID[ 4]: S-1-5-11
SID[ 5]: S-1-22-2-502
SID[ 6]: S-1-22-1-500
SE_PRIV 0x0 0x0 0x0 0x0
[2010/12/14 06:39:31.977454, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 500
Primary group is 503 and contains 2 supplementary groups
Group[ 0]: 503
Group[ 1]: 502
[2010/12/14 06:39:31.978047, 5] smbd/uid.c:354(change_to_user)
change_to_user uid=(0,500) gid=(0,503)
[2010/12/14 06:39:31.978423, 10]
smbd/service.c:163(set_conn_connectpath)
set_conn_connectpath: service sys, connectpath = /share/system
[2010/12/14 06:39:31.978713, 10]
modules/vfs_default.c:152(vfswrap_fs_capabilities)
vfswrap_fs_capabilities: timestamp resolution of sec available on
share sys, directory /share/system
[2010/12/14 06:39:31.978932, 1]
smbd/service.c:1070(make_connection_snum)
fedorabox (::1) connect to service sys initially as user cstelter
(uid=500, gid=503) (pid 12678)
[2010/12/14 06:39:31.979307, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:31.979504, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:31.979671, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:31.980025, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:31.980338, 3] smbd/reply.c:865(reply_tcon_and_X)
tconX service=SYS
[2010/12/14 06:39:32.637368, 10]
lib/util_sock.c:726(read_smb_length_return_keepalive)
got smb length of 86
[2010/12/14 06:39:32.637777, 6] smbd/process.c:1482(process_smb)
got message type 0x0 of len 0x56
[2010/12/14 06:39:32.638061, 3] smbd/process.c:1485(process_smb)
Transaction 7 of length 90 (0 toread)
[2010/12/14 06:39:32.638248, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:32.638362, 5] lib/util.c:627(show_msg)
size=86
smb_com=0x32
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=8
smb_flg2=51201
smb_tid=1
smb_pid=12676
smb_uid=100
smb_mid=9
smt_wct=15
smb_vwv[ 0]= 18 (0x12)
smb_vwv[ 1]= 0 (0x0)
smb_vwv[ 2]= 10 (0xA)
smb_vwv[ 3]=16644 (0x4104)
smb_vwv[ 4]= 0 (0x0)
smb_vwv[ 5]= 0 (0x0)
smb_vwv[ 6]= 0 (0x0)
smb_vwv[ 7]= 0 (0x0)
smb_vwv[ 8]= 0 (0x0)
smb_vwv[ 9]= 18 (0x12)
smb_vwv[10]= 68 (0x44)
smb_vwv[11]= 0 (0x0)
smb_vwv[12]= 86 (0x56)
smb_vwv[13]= 1 (0x1)
smb_vwv[14]= 1 (0x1)
smb_bcc=21
[2010/12/14 06:39:32.639738, 10] ../lib/util/util.c:278(_dump_data)
[0000] 00 44 20 16 00 56 05 06 00 04 01 00 00 00 00
5C .D ..V.. .......\
[0010] 00 2A 00 00 00 .*...
[2010/12/14 06:39:32.642877, 3] smbd/process.c:1294(switch_message)
switch message SMBtrans2 (pid 12678) conn 0x149a7f0
[2010/12/14 06:39:32.643261, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (500, 503) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:32.643460, 5]
auth/token_util.c:531(debug_nt_user_token)
NT user token of user S-1-5-21-1265826603-3207742958-388371694-1001
contains 7 SIDs
SID[ 0]: S-1-5-21-1265826603-3207742958-388371694-1001
SID[ 1]: S-1-22-2-503
SID[ 2]: S-1-1-0
SID[ 3]: S-1-5-2
SID[ 4]: S-1-5-11
SID[ 5]: S-1-22-2-502
SID[ 6]: S-1-22-1-500
SE_PRIV 0x0 0x0 0x0 0x0
[2010/12/14 06:39:32.644210, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 500
Primary group is 503 and contains 2 supplementary groups
Group[ 0]: 503
Group[ 1]: 502
[2010/12/14 06:39:32.644704, 5] smbd/uid.c:354(change_to_user)
change_to_user uid=(0,500) gid=(0,503)
[2010/12/14 06:39:32.644894, 4] smbd/vfs.c:721(vfs_ChDir)
vfs_ChDir to /share/system
[2010/12/14 06:39:32.645279, 3]
smbd/trans2.c:2252(call_trans2findfirst)
call_trans2findfirst: dirtype = 16, maxentries = 1366,
close_after_first=0, close_if_end = 1 requires_resume_key = 1 level =
0x104, max_data_bytes = 16644
[2010/12/14 06:39:32.645587, 5] smbd/filename.c:169(unix_convert)
unix_convert called on file "*"
[2010/12/14 06:39:32.645840, 10] smbd/statcache.c:236(stat_cache_lookup)
stat_cache_lookup: lookup failed for name [*]
[2010/12/14 06:39:32.646127, 5] smbd/filename.c:328(unix_convert)
unix_convert begin: name = *, dirpath = , start = *
[2010/12/14 06:39:32.646340, 10] smbd/mangle_hash2.c:239(is_mangled)
is_mangled * ?
[2010/12/14 06:39:32.646517, 10]
smbd/mangle_hash2.c:178(is_mangled_component)
is_mangled_component * (len 1) ?
[2010/12/14 06:39:32.646721, 10] smbd/mangle_hash2.c:239(is_mangled)
is_mangled * ?
[2010/12/14 06:39:32.646890, 10]
smbd/mangle_hash2.c:178(is_mangled_component)
is_mangled_component * (len 1) ?
[2010/12/14 06:39:32.647157, 5] smbd/filename.c:650(unix_convert)
New file *
[2010/12/14 06:39:32.647369, 3] smbd/vfs.c:851(check_reduced_name)
check_reduced_name [*] [/share/system]
[2010/12/14 06:39:32.647661, 10] smbd/vfs.c:938(check_reduced_name)
check_reduced_name realpath [*] -> [/share/system/*]
[2010/12/14 06:39:32.647842, 3] smbd/vfs.c:1008(check_reduced_name)
check_reduced_name: * reduced to /share/system/*
[2010/12/14 06:39:32.648112, 5]
smbd/trans2.c:2333(call_trans2findfirst)
dir=./, mask = *
[2010/12/14 06:39:32.648382, 5] smbd/dir.c:423(dptr_create)
dptr_create dir=./
[2010/12/14 06:39:32.648594, 3] smbd/vfs.c:851(check_reduced_name)
check_reduced_name [./] [/share/system]
[2010/12/14 06:39:32.648785, 10] smbd/vfs.c:938(check_reduced_name)
check_reduced_name realpath [./] -> [/share/system]
[2010/12/14 06:39:32.649036, 3] smbd/vfs.c:1008(check_reduced_name)
check_reduced_name: ./ reduced to /share/system
[2010/12/14 06:39:32.649410, 5] smbd/dir.c:1331(OpenDir)
OpenDir: Can't open ./. Permission denied
[2010/12/14 06:39:32.649742, 3] smbd/error.c:80(error_packet_set)
error packet at smbd/trans2.c(2395) cmd=50 (SMBtrans2)
NT_STATUS_ACCESS_DENIED
[2010/12/14 06:39:32.650042, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:32.650212, 5] lib/util.c:627(show_msg)
size=35
smb_com=0x32
smb_rcls=34
smb_reh=0
smb_err=49152
smb_flg=136
smb_flg2=51203
smb_tid=1
smb_pid=12676
smb_uid=100
smb_mid=9
smt_wct=0
smb_bcc=0
[2010/12/14 06:39:32.662303, 10]
lib/util_sock.c:726(read_smb_length_return_keepalive)
got smb length of 35
[2010/12/14 06:39:32.662714, 6] smbd/process.c:1482(process_smb)
got message type 0x0 of len 0x23
[2010/12/14 06:39:32.662907, 3] smbd/process.c:1485(process_smb)
Transaction 8 of length 39 (0 toread)
[2010/12/14 06:39:32.663170, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:32.663294, 5] lib/util.c:627(show_msg)
size=35
smb_com=0x80
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=8
smb_flg2=51201
smb_tid=1
smb_pid=12676
smb_uid=100
smb_mid=10
smt_wct=0
smb_bcc=0
[2010/12/14 06:39:32.663933, 3] smbd/process.c:1294(switch_message)
switch message SMBdskattr (pid 12678) conn 0x149a7f0
[2010/12/14 06:39:32.664206, 4] smbd/uid.c:257(change_to_user)
change_to_user: Skipping user change - already user
[2010/12/14 06:39:32.674410, 10] lib/sysquotas.c:431(sys_get_quota)
sys_get_quota() uid(0, 500)
[2010/12/14 06:39:32.674781, 10]
lib/sysquotas_linux.c:319(sys_get_linux_gen_quota)
sys_get_linux_gen_quota: path[/]
bdev[/dev/mapper/vg_fedorabox-lv_root] SMB_USER_QUOTA_TYPE uid[500]
[2010/12/14 06:39:32.675217, 10]
lib/sysquotas_linux.c:185(sys_get_linux_v2_quota)
sys_get_linux_v2_quota: path[/] bdev[/dev/mapper/vg_fedorabox-lv_root]
SMB_USER_QUOTA_TYPE uid[500]
[2010/12/14 06:39:32.675506, 10]
lib/sysquotas_linux.c:51(sys_get_linux_v1_quota)
sys_get_linux_v1_quota: path[/] bdev[/dev/mapper/vg_fedorabox-lv_root]
SMB_USER_QUOTA_TYPE uid[500]
[2010/12/14 06:39:32.675864, 3] lib/sysquotas.c:453(sys_get_quota)
sys_get_vfs_quota() failed for mntpath[/]
bdev[/dev/mapper/vg_fedorabox-lv_root] qtype[2] id[500]: Operation not
permitted
[2010/12/14 06:39:32.683345, 10] lib/sysquotas.c:431(sys_get_quota)
sys_get_quota() uid(0, 500)
[2010/12/14 06:39:32.683702, 10]
lib/sysquotas_linux.c:328(sys_get_linux_gen_quota)
sys_get_linux_gen_quota: path[/]
bdev[/dev/mapper/vg_fedorabox-lv_root] SMB_GROUP_QUOTA_TYPE gid[503]
[2010/12/14 06:39:32.684058, 10]
lib/sysquotas_linux.c:194(sys_get_linux_v2_quota)
sys_get_linux_v2_quota: path[/] bdev[/dev/mapper/vg_fedorabox-lv_root]
SMB_GROUP_QUOTA_TYPE gid[503]
[2010/12/14 06:39:32.684343, 10]
lib/sysquotas_linux.c:60(sys_get_linux_v1_quota)
sys_get_linux_v1_quota: path[/] bdev[/dev/mapper/vg_fedorabox-lv_root]
SMB_GROUP_QUOTA_TYPE gid[503]
[2010/12/14 06:39:32.684574, 3] lib/sysquotas.c:453(sys_get_quota)
sys_get_vfs_quota() failed for mntpath[/]
bdev[/dev/mapper/vg_fedorabox-lv_root] qtype[4] id[503]: Operation not
permitted
[2010/12/14 06:39:32.684837, 3] smbd/reply.c:1314(reply_dskattr)
dskattr dfree=38437
[2010/12/14 06:39:32.689520, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:32.689817, 5] lib/util.c:627(show_msg)
size=45
smb_com=0x80
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=136
smb_flg2=51203
smb_tid=1
smb_pid=12676
smb_uid=100
smb_mid=10
smt_wct=5
smb_vwv[ 0]=60475 (0xEC3B)
smb_vwv[ 1]= 512 (0x200)
smb_vwv[ 2]= 512 (0x200)
smb_vwv[ 3]=38437 (0x9625)
smb_vwv[ 4]= 0 (0x0)
smb_bcc=0
[2010/12/14 06:39:38.966130, 10]
lib/util_sock.c:726(read_smb_length_return_keepalive)
got smb length of 35
[2010/12/14 06:39:38.966539, 6] smbd/process.c:1482(process_smb)
got message type 0x0 of len 0x23
[2010/12/14 06:39:38.966731, 3] smbd/process.c:1485(process_smb)
Transaction 9 of length 39 (0 toread)
[2010/12/14 06:39:38.966898, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:38.967097, 5] lib/util.c:627(show_msg)
size=35
smb_com=0x71
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=8
smb_flg2=51201
smb_tid=1
smb_pid=12676
smb_uid=100
smb_mid=11
smt_wct=0
smb_bcc=0
[2010/12/14 06:39:38.967767, 3] smbd/process.c:1294(switch_message)
switch message SMBtdis (pid 12678) conn 0x149a7f0
[2010/12/14 06:39:38.967956, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:38.970616, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:38.970799, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:38.971207, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:38.971471, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:38.971665, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:38.971833, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:38.972192, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:38.972411, 1] smbd/service.c:1251(close_cnum)
fedorabox (::1) closed connection to service sys
[2010/12/14 06:39:38.972634, 3] smbd/connection.c:31(yield_connection)
Yielding connection to sys
[2010/12/14 06:39:38.975089, 10]
lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
Locking key 86310000FFFFFFFF0100
[2010/12/14 06:39:38.975603, 10]
lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
Allocated locked data 0x0x148ebc0
[2010/12/14 06:39:38.975918, 10]
lib/dbwrap_tdb.c:42(db_tdb_record_destr)
Unlocking key 86310000FFFFFFFF0100
[2010/12/14 06:39:38.976542, 4] smbd/vfs.c:721(vfs_ChDir)
vfs_ChDir to /
[2010/12/14 06:39:38.976799, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:38.977155, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:38.977385, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:38.977661, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:38.977923, 5]
lib/messages.c:329(messaging_deregister)
Deregistering messaging pointer for type 784 - private_data=0x141d1d0
[2010/12/14 06:39:38.986336, 5] lib/util.c:617(show_msg)
[2010/12/14 06:39:38.986635, 5] lib/util.c:627(show_msg)
size=35
smb_com=0x71
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=136
smb_flg2=51203
smb_tid=1
smb_pid=12676
smb_uid=100
smb_mid=11
smt_wct=0
smb_bcc=0
[2010/12/14 06:39:38.991503, 5]
lib/util_sock.c:462(read_fd_with_timeout)
read_fd_with_timeout: blocking read. EOF from client.
[2010/12/14 06:39:38.991901, 10]
smbd/process.c:286(receive_smb_raw_talloc)
receive_smb_raw: NT_STATUS_END_OF_FILE
[2010/12/14 06:39:39.005802, 3] smbd/sec_ctx.c:310(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/12/14 06:39:39.009521, 5]
auth/token_util.c:525(debug_nt_user_token)
NT user token: (NULL)
[2010/12/14 06:39:39.009760, 5]
auth/token_util.c:551(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2010/12/14 06:39:39.010120, 5] smbd/uid.c:369(change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/12/14 06:39:39.010399, 10]
lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
Locking key 49442F31323637382F31
[2010/12/14 06:39:39.010703, 10]
lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
Allocated locked data 0x0x1496490
[2010/12/14 06:39:39.011089, 10]
lib/dbwrap_tdb.c:42(db_tdb_record_destr)
Unlocking key 49442F31323637382F31
[2010/12/14 06:39:39.011478, 3] smbd/connection.c:31(yield_connection)
Yielding connection to
[2010/12/14 06:39:39.011960, 10]
lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
Locking key 86310000FFFFFFFFFFFF
[2010/12/14 06:39:39.019124, 10]
lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
Allocated locked data 0x0x148ebc0
[2010/12/14 06:39:39.019489, 10]
lib/dbwrap_tdb.c:42(db_tdb_record_destr)
Unlocking key 86310000FFFFFFFFFFFF
[2010/12/14 06:39:39.020318, 3] smbd/server.c:902(exit_server_common)
Server exit (failed to receive smb request)
On Tue, 2010-12-14 at 08:20 +0100, Volker Lendecke wrote:
> On Mon, Dec 13, 2010 at 02:00:54PM -0800, stelter at sonic.net wrote:
> > smb: > dir
> > NT_STATUS_ACCESS_DENIED listing *
>
> The debug level 10 logs will probably show you the reason.
> If you look at them, you will find the
> NT_STATUS_ACCESS_DENIED in there, go back a bit from that
> message. Maybe you find something enlightening.
>
> Volker
More information about the samba
mailing list