[Samba] First write fails subsequent succeeds
Jason Pfingstmann
jpfingstmann at stcvideo.com
Wed Oct 15 21:01:11 GMT 2003
I have been having a problem that has had me scratching my head for the
last week and a half. I have searched with google, read the 13th hour
troubleshooting guide (stopped when it got to the sniffer diagnostics),
and have even upgraded samba to 3.0.0. I will post a description followed
by a level 4 debug log file.
When a user opens the "Public" share they can browse the share without
error and open documents without error, however, when trying to write to
Public, they cannot. When opening the share with Windows 2000
(Authenticating as Guest on the server) I right click and choose Create
Folder. I get an error "Unable to create folder "New Folder" Cannot
create a file when that file exists." and the folder "New Folder" DOES get
created. When trying to save a file to the server, I get "Cannot copy
test.txt: The specified network name is no longer available." and a 0 byte
file with the right file name. If I delete the file and try recopying it,
it copies fine. It seems that the first attempt to write to the share
after opening it fails, further attempts work. After I get the error that
I can't create the folder, I can delete the "New Folder" and create it
again without an errors. As soon as I get out of the share and go back
in, I get the errors again. I have searched for this issue on google, but
am having difficulty describing the issue to get a good search result.
Here is a level 4 debug log:
[2003/10/15 13:32:11, 1] smbd/service.c:make_connection_snum(698)
kim (192.168.0.144) connect to service Public initially as user bleith
(uid=1001, gid=100) (pid 11983)
[2003/10/15 13:32:13, 3] smbd/process.c:process_smb(890)
Transaction 1 of length 137
[2003/10/15 13:32:13, 3] smbd/process.c:switch_message(685)
switch message SMBnegprot (pid 11994)
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/negprot.c:reply_negprot(455)
Requested protocol [PC NETWORK PROGRAM 1.0]
[2003/10/15 13:32:13, 3] smbd/negprot.c:reply_negprot(455)
Requested protocol [LANMAN1.0]
[2003/10/15 13:32:13, 3] smbd/negprot.c:reply_negprot(455)
Requested protocol [Windows for Workgroups 3.1a]
[2003/10/15 13:32:13, 3] smbd/negprot.c:reply_negprot(455)
Requested protocol [LM1.2X002]
[2003/10/15 13:32:13, 3] smbd/negprot.c:reply_negprot(455)
Requested protocol [LANMAN2.1]
[2003/10/15 13:32:13, 3] smbd/negprot.c:reply_negprot(455)
Requested protocol [NT LM 0.12]
[2003/10/15 13:32:13, 3] smbd/negprot.c:reply_nt1(329)
using SPNEGO
[2003/10/15 13:32:13, 3] smbd/negprot.c:reply_negprot(532)
Selected protocol NT LM 0.12
[2003/10/15 13:32:13, 3] smbd/process.c:process_smb(890)
Transaction 2 of length 210
[2003/10/15 13:32:13, 3] smbd/process.c:switch_message(685)
switch message SMBsesssetupX (pid 11994)
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/sesssetup.c:reply_sesssetup_and_X(579)
wct=12 flg2=0xc807
[2003/10/15 13:32:13, 2] smbd/sesssetup.c:setup_new_vc_session(535)
setup_new_vc_session: New VC == 0, if NT4.x compatible we would close
all old resources.
[2003/10/15 13:32:13, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(476)
Doing spnego session setup
[2003/10/15 13:32:13, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(500)
NativeOS=[Windows 2000 2195] NativeLanMan=[Windows 2000 5.0]
[2003/10/15 13:32:13, 3] smbd/sesssetup.c:reply_spnego_negotiate(385)
Got OID 1 3 6 1 4 1 311 2 2 10
[2003/10/15 13:32:13, 3] smbd/sesssetup.c:reply_spnego_negotiate(388)
Got secblob of size 41
[2003/10/15 13:32:13, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(33)
Got NTLMSSP neg_flags=0xe000b297
NTLMSSP_NEGOTIATE_UNICODE
NTLMSSP_NEGOTIATE_OEM
NTLMSSP_REQUEST_TARGET
NTLMSSP_NEGOTIATE_SIGN
NTLMSSP_NEGOTIATE_LM_KEY
NTLMSSP_NEGOTIATE_NTLM
NTLMSSP_NEGOTIATE_DOMAIN_SUPPLIED
NTLMSSP_NEGOTIATE_WORKSTATION_SUPPLIED
NTLMSSP_NEGOTIATE_ALWAYS_SIGN
NTLMSSP_NEGOTIATE_128
NTLMSSP_NEGOTIATE_KEY_EXCH
[2003/10/15 13:32:13, 3] smbd/process.c:process_smb(890)
Transaction 3 of length 298
[2003/10/15 13:32:13, 3] smbd/process.c:switch_message(685)
switch message SMBsesssetupX (pid 11994)
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/sesssetup.c:reply_sesssetup_and_X(579)
wct=12 flg2=0xc807
[2003/10/15 13:32:13, 2] smbd/sesssetup.c:setup_new_vc_session(535)
setup_new_vc_session: New VC == 0, if NT4.x compatible we would close
all old resources.
[2003/10/15 13:32:13, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(476)
Doing spnego session setup
[2003/10/15 13:32:13, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(500)
NativeOS=[Windows 2000 2195] NativeLanMan=[Windows 2000 5.0]
[2003/10/15 13:32:13, 3] libsmb/ntlmssp.c:ntlmssp_server_auth(286)
Got user=[Administrator] domain=[KIM] workstation=[KIM] len1=24 len2=24
[2003/10/15 13:32:13, 3] auth/auth.c:check_ntlm_password(216)
check_ntlm_password: Checking password for unmapped user
[KIM]\[Administrator]@[KIM] with the new password interface
[2003/10/15 13:32:13, 3] auth/auth.c:check_ntlm_password(219)
check_ntlm_password: mapped user is: [NIRVANA]\[Administrator]@[KIM]
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:push_sec_ctx(256)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/10/15 13:32:13, 3] smbd/uid.c:push_conn_ctx(287)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] auth/auth_sam.c:check_sam_security(439)
Couldn't find user 'Administrator' in passdb file.
[2003/10/15 13:32:13, 2] auth/auth.c:check_ntlm_password(309)
check_ntlm_password: Authentication for user [Administrator] ->
[Administrator] FAILED with error NT_STATUS_NO_SUCH_USER
[2003/10/15 13:32:13, 3] smbd/sesssetup.c:do_map_to_guest(41)
No such user Administrator [KIM] - using guest account
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:push_sec_ctx(256)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/10/15 13:32:13, 3] smbd/uid.c:push_conn_ctx(287)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:push_sec_ctx(256)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/10/15 13:32:13, 3] smbd/uid.c:push_conn_ctx(287)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:push_sec_ctx(256)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/10/15 13:32:13, 3] smbd/uid.c:push_conn_ctx(287)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] passdb/lookup_sid.c:fetch_sid_from_gid_cache(235)
fetch sid from gid cache 100 ->
S-1-5-21-2730512542-3254095339-111969469-1201
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:push_sec_ctx(256)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/10/15 13:32:13, 3] smbd/uid.c:push_conn_ctx(287)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/password.c:register_vuid(207)
User name: bleith Real name: bleith
[2003/10/15 13:32:13, 3] smbd/password.c:register_vuid(225)
UNIX uid 1001 is UNIX user bleith, and will be vuid 100
[2003/10/15 13:32:13, 3] smbd/process.c:process_smb(890)
Transaction 4 of length 88
[2003/10/15 13:32:13, 3] smbd/process.c:switch_message(685)
switch message SMBtconX (pid 11994)
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 4] smbd/reply.c:reply_tcon_and_X(266)
Client requested device type [?????] for share [PUBLIC]
[2003/10/15 13:32:13, 3] smbd/service.c:make_connection_snum(536)
Connect path is '/shares/Public' for service [Public]
[2003/10/15 13:32:13, 4] rpc_server/srv_srvsvc_nt.c:get_share_security(217)
get_share_security: using default secdesc for Public
[2003/10/15 13:32:13, 3] lib/util_seaccess.c:se_access_check(267)
[2003/10/15 13:32:13, 3] lib/util_seaccess.c:se_access_check(268)
se_access_check: user sid is S-1-5-21-2730512542-3254095339-111969469-501
se_access_check: also S-1-5-21-2730512542-3254095339-111969469-514
se_access_check: also S-1-1-0
se_access_check: also S-1-5-2
se_access_check: also S-1-5-32-546
se_access_check: also S-1-5-21-2730512542-3254095339-111969469-1201
se_access_check: also S-1-5-21-2730512542-3254095339-111969469-1817
[2003/10/15 13:32:13, 3] smbd/vfs.c:vfs_init_default(201)
Initialising default vfs hooks
[2003/10/15 13:32:13, 4] rpc_server/srv_srvsvc_nt.c:get_share_security(217)
get_share_security: using default secdesc for Public
[2003/10/15 13:32:13, 3] lib/util_seaccess.c:se_access_check(267)
[2003/10/15 13:32:13, 3] lib/util_seaccess.c:se_access_check(268)
se_access_check: user sid is S-1-5-21-2730512542-3254095339-111969469-501
se_access_check: also S-1-5-21-2730512542-3254095339-111969469-514
se_access_check: also S-1-1-0
se_access_check: also S-1-5-2
se_access_check: also S-1-5-32-546
se_access_check: also S-1-5-21-2730512542-3254095339-111969469-1201
se_access_check: also S-1-5-21-2730512542-3254095339-111969469-1817
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (1001, 100) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 1] smbd/service.c:make_connection_snum(698)
kim (192.168.0.144) connect to service Public initially as user bleith
(uid=1001, gid=100) (pid 11994)
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/reply.c:reply_tcon_and_X(314)
tconX service=PUBLIC
[2003/10/15 13:32:13, 3] smbd/process.c:process_smb(890)
Transaction 5 of length 90
[2003/10/15 13:32:13, 3] smbd/process.c:switch_message(685)
switch message SMBntcreateX (pid 11994)
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (1001, 100) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 4] smbd/vfs.c:vfs_ChDir(611)
vfs_ChDir to /shares/Public
[2003/10/15 13:32:13, 3] lib/util.c:unix_clean_name(580)
unix_clean_name []
[2003/10/15 13:32:13, 3] smbd/dosmode.c:unix_mode(110)
unix_mode(.) returning 0764
[2003/10/15 13:32:13, 3] smbd/error.c:error_packet(94)
error string = File exists
[2003/10/15 13:32:13, 3] smbd/error.c:error_packet(113)
error packet at smbd/trans2.c(1797) cmd=162 (SMBntcreateX)
NT_STATUS_OBJECT_NAME_COLLISION
[2003/10/15 13:32:13, 3] smbd/process.c:process_smb(890)
Transaction 6 of length 120
[2003/10/15 13:32:13, 3] smbd/process.c:switch_message(685)
switch message SMBntcreateX (pid 11994)
[2003/10/15 13:32:13, 4] smbd/uid.c:change_to_user(122)
change_to_user: Skipping user change - already user
[2003/10/15 13:32:13, 3] lib/util.c:unix_clean_name(580)
unix_clean_name [/New Folder (4)]
[2003/10/15 13:32:13, 3] smbd/dosmode.c:unix_mode(110)
unix_mode(New Folder (4)) returning 0764
[2003/10/15 13:32:13, 3] smbd/error.c:error_packet(94)
error string = File exists
[2003/10/15 13:32:13, 3] smbd/error.c:error_packet(113)
error packet at smbd/trans2.c(1797) cmd=162 (SMBntcreateX)
NT_STATUS_OBJECT_NAME_COLLISION
[2003/10/15 13:32:13, 3] smbd/process.c:process_smb(890)
Transaction 7 of length 43
[2003/10/15 13:32:13, 3] smbd/process.c:switch_message(685)
switch message SMBulogoffX (pid 11994)
[2003/10/15 13:32:13, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:13, 3] smbd/reply.c:reply_ulogoffX(1055)
ulogoffX vuid=100
[2003/10/15 13:32:15, 3] smbd/process.c:process_smb(890)
Transaction 8 of length 90
[2003/10/15 13:32:15, 3] smbd/process.c:switch_message(685)
switch message SMBtrans2 (pid 11994)
[2003/10/15 13:32:15, 2] smbd/uid.c:change_to_user(141)
change_to_user: Invalid vuid used 100 or vuid not permitted access to
share.
[2003/10/15 13:32:15, 3] smbd/error.c:error_packet(113)
error packet at smbd/process.c(739) cmd=50 (SMBtrans2)
NT_STATUS_NETWORK_ACCESS_DENIED
[2003/10/15 13:32:15, 3] smbd/process.c:process_smb(890)
Transaction 9 of length 39
[2003/10/15 13:32:15, 3] smbd/process.c:switch_message(685)
switch message SMBtdis (pid 11994)
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 1] smbd/service.c:close_cnum(880)
kim (192.168.0.144) closed connection to service Public
[2003/10/15 13:32:15, 3] smbd/connection.c:yield_connection(69)
Yielding connection to Public
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 4] smbd/vfs.c:vfs_ChDir(611)
vfs_ChDir to /
[2003/10/15 13:32:15, 3] smbd/process.c:process_smb(890)
Transaction 10 of length 43
[2003/10/15 13:32:15, 3] smbd/process.c:switch_message(685)
switch message SMBulogoffX (pid 11994)
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/reply.c:reply_ulogoffX(1044)
ulogoff, vuser id 100 does not map to user.
[2003/10/15 13:32:15, 3] smbd/reply.c:reply_ulogoffX(1055)
ulogoffX vuid=100
[2003/10/15 13:32:15, 3] smbd/process.c:process_smb(890)
Transaction 11 of length 43
[2003/10/15 13:32:15, 3] smbd/process.c:switch_message(685)
switch message SMBulogoffX (pid 11994)
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/reply.c:reply_ulogoffX(1044)
ulogoff, vuser id 102 does not map to user.
[2003/10/15 13:32:15, 3] smbd/reply.c:reply_ulogoffX(1055)
ulogoffX vuid=102
[2003/10/15 13:32:15, 3] smbd/process.c:timeout_processing(1099)
timeout_processing: End of file from client (client has disconnected).
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 2] smbd/server.c:exit_server(558)
Closing connections
[2003/10/15 13:32:15, 3] smbd/connection.c:yield_connection(69)
Yielding connection to
[2003/10/15 13:32:15, 3] smbd/server.c:exit_server(601)
Server exit (normal exit)
[2003/10/15 13:32:15, 3] smbd/process.c:process_smb(890)
Transaction 1 of length 137
[2003/10/15 13:32:15, 3] smbd/process.c:switch_message(685)
switch message SMBnegprot (pid 11995)
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/negprot.c:reply_negprot(455)
Requested protocol [PC NETWORK PROGRAM 1.0]
[2003/10/15 13:32:15, 3] smbd/negprot.c:reply_negprot(455)
Requested protocol [LANMAN1.0]
[2003/10/15 13:32:15, 3] smbd/negprot.c:reply_negprot(455)
Requested protocol [Windows for Workgroups 3.1a]
[2003/10/15 13:32:15, 3] smbd/negprot.c:reply_negprot(455)
Requested protocol [LM1.2X002]
[2003/10/15 13:32:15, 3] smbd/negprot.c:reply_negprot(455)
Requested protocol [LANMAN2.1]
[2003/10/15 13:32:15, 3] smbd/negprot.c:reply_negprot(455)
Requested protocol [NT LM 0.12]
[2003/10/15 13:32:15, 3] smbd/negprot.c:reply_nt1(329)
using SPNEGO
[2003/10/15 13:32:15, 3] smbd/negprot.c:reply_negprot(532)
Selected protocol NT LM 0.12
[2003/10/15 13:32:15, 3] smbd/process.c:process_smb(890)
Transaction 2 of length 210
[2003/10/15 13:32:15, 3] smbd/process.c:switch_message(685)
switch message SMBsesssetupX (pid 11995)
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/sesssetup.c:reply_sesssetup_and_X(579)
wct=12 flg2=0xc807
[2003/10/15 13:32:15, 2] smbd/sesssetup.c:setup_new_vc_session(535)
setup_new_vc_session: New VC == 0, if NT4.x compatible we would close
all old resources.
[2003/10/15 13:32:15, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(476)
Doing spnego session setup
[2003/10/15 13:32:15, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(500)
NativeOS=[Windows 2000 2195] NativeLanMan=[Windows 2000 5.0]
[2003/10/15 13:32:15, 3] smbd/sesssetup.c:reply_spnego_negotiate(385)
Got OID 1 3 6 1 4 1 311 2 2 10
[2003/10/15 13:32:15, 3] smbd/sesssetup.c:reply_spnego_negotiate(388)
Got secblob of size 41
[2003/10/15 13:32:15, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(33)
Got NTLMSSP neg_flags=0xe000b297
NTLMSSP_NEGOTIATE_UNICODE
NTLMSSP_NEGOTIATE_OEM
NTLMSSP_REQUEST_TARGET
NTLMSSP_NEGOTIATE_SIGN
NTLMSSP_NEGOTIATE_LM_KEY
NTLMSSP_NEGOTIATE_NTLM
NTLMSSP_NEGOTIATE_DOMAIN_SUPPLIED
NTLMSSP_NEGOTIATE_WORKSTATION_SUPPLIED
NTLMSSP_NEGOTIATE_ALWAYS_SIGN
NTLMSSP_NEGOTIATE_128
NTLMSSP_NEGOTIATE_KEY_EXCH
[2003/10/15 13:32:15, 3] smbd/process.c:process_smb(890)
Transaction 3 of length 298
[2003/10/15 13:32:15, 3] smbd/process.c:switch_message(685)
switch message SMBsesssetupX (pid 11995)
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/sesssetup.c:reply_sesssetup_and_X(579)
wct=12 flg2=0xc807
[2003/10/15 13:32:15, 2] smbd/sesssetup.c:setup_new_vc_session(535)
setup_new_vc_session: New VC == 0, if NT4.x compatible we would close
all old resources.
[2003/10/15 13:32:15, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(476)
Doing spnego session setup
[2003/10/15 13:32:15, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(500)
NativeOS=[Windows 2000 2195] NativeLanMan=[Windows 2000 5.0]
[2003/10/15 13:32:15, 3] libsmb/ntlmssp.c:ntlmssp_server_auth(286)
Got user=[Administrator] domain=[KIM] workstation=[KIM] len1=24 len2=24
[2003/10/15 13:32:15, 3] auth/auth.c:check_ntlm_password(216)
check_ntlm_password: Checking password for unmapped user
[KIM]\[Administrator]@[KIM] with the new password interface
[2003/10/15 13:32:15, 3] auth/auth.c:check_ntlm_password(219)
check_ntlm_password: mapped user is: [NIRVANA]\[Administrator]@[KIM]
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:push_sec_ctx(256)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/10/15 13:32:15, 3] smbd/uid.c:push_conn_ctx(287)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] auth/auth_sam.c:check_sam_security(439)
Couldn't find user 'Administrator' in passdb file.
[2003/10/15 13:32:15, 2] auth/auth.c:check_ntlm_password(309)
check_ntlm_password: Authentication for user [Administrator] ->
[Administrator] FAILED with error NT_STATUS_NO_SUCH_USER
[2003/10/15 13:32:15, 3] smbd/sesssetup.c:do_map_to_guest(41)
No such user Administrator [KIM] - using guest account
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:push_sec_ctx(256)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/10/15 13:32:15, 3] smbd/uid.c:push_conn_ctx(287)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:push_sec_ctx(256)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/10/15 13:32:15, 3] smbd/uid.c:push_conn_ctx(287)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:push_sec_ctx(256)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/10/15 13:32:15, 3] smbd/uid.c:push_conn_ctx(287)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] passdb/lookup_sid.c:fetch_sid_from_gid_cache(235)
fetch sid from gid cache 100 ->
S-1-5-21-2730512542-3254095339-111969469-1201
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:push_sec_ctx(256)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2003/10/15 13:32:15, 3] smbd/uid.c:push_conn_ctx(287)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/password.c:register_vuid(207)
User name: bleith Real name: bleith
[2003/10/15 13:32:15, 3] smbd/password.c:register_vuid(225)
UNIX uid 1001 is UNIX user bleith, and will be vuid 100
[2003/10/15 13:32:15, 3] smbd/process.c:process_smb(890)
Transaction 4 of length 88
[2003/10/15 13:32:15, 3] smbd/process.c:switch_message(685)
switch message SMBtconX (pid 11995)
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 4] smbd/reply.c:reply_tcon_and_X(266)
Client requested device type [?????] for share [PUBLIC]
[2003/10/15 13:32:15, 3] smbd/service.c:make_connection_snum(536)
Connect path is '/shares/Public' for service [Public]
[2003/10/15 13:32:15, 4] rpc_server/srv_srvsvc_nt.c:get_share_security(217)
get_share_security: using default secdesc for Public
[2003/10/15 13:32:15, 3] lib/util_seaccess.c:se_access_check(267)
[2003/10/15 13:32:15, 3] lib/util_seaccess.c:se_access_check(268)
se_access_check: user sid is S-1-5-21-2730512542-3254095339-111969469-501
se_access_check: also S-1-5-21-2730512542-3254095339-111969469-514
se_access_check: also S-1-1-0
se_access_check: also S-1-5-2
se_access_check: also S-1-5-32-546
se_access_check: also S-1-5-21-2730512542-3254095339-111969469-1201
se_access_check: also S-1-5-21-2730512542-3254095339-111969469-1817
[2003/10/15 13:32:15, 3] smbd/vfs.c:vfs_init_default(201)
Initialising default vfs hooks
[2003/10/15 13:32:15, 4] rpc_server/srv_srvsvc_nt.c:get_share_security(217)
get_share_security: using default secdesc for Public
[2003/10/15 13:32:15, 3] lib/util_seaccess.c:se_access_check(267)
[2003/10/15 13:32:15, 3] lib/util_seaccess.c:se_access_check(268)
se_access_check: user sid is S-1-5-21-2730512542-3254095339-111969469-501
se_access_check: also S-1-5-21-2730512542-3254095339-111969469-514
se_access_check: also S-1-1-0
se_access_check: also S-1-5-2
se_access_check: also S-1-5-32-546
se_access_check: also S-1-5-21-2730512542-3254095339-111969469-1201
se_access_check: also S-1-5-21-2730512542-3254095339-111969469-1817
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (1001, 100) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 1] smbd/service.c:make_connection_snum(698)
kim (192.168.0.144) connect to service Public initially as user bleith
(uid=1001, gid=100) (pid 11995)
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 3] smbd/reply.c:reply_tcon_and_X(314)
tconX service=PUBLIC
[2003/10/15 13:32:15, 3] smbd/process.c:process_smb(890)
Transaction 5 of length 90
[2003/10/15 13:32:15, 3] smbd/process.c:switch_message(685)
switch message SMBtrans2 (pid 11995)
[2003/10/15 13:32:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (1001, 100) - sec_ctx_stack_ndx = 0
[2003/10/15 13:32:15, 4] smbd/vfs.c:vfs_ChDir(611)
vfs_ChDir to /shares/Public
[2003/10/15 13:32:15, 3] smbd/trans2.c:call_trans2findfirst(951)
call_trans2findfirst: dirtype = 22, maxentries = 1366,
close_after_first=0, close_if_end = 1 requires_resume_key = 1 level =
260, max_data_bytes = 16384
[2003/10/15 13:32:15, 3] lib/util.c:unix_clean_name(580)
unix_clean_name [/*]
[2003/10/15 13:32:15, 3] lib/util.c:unix_clean_name(580)
unix_clean_name [*]
[2003/10/15 13:32:15, 3] lib/util.c:unix_clean_name(580)
unix_clean_name [./]
[2003/10/15 13:32:15, 3] smbd/dir.c:dptr_create(491)
creating new dirptr 256 for path ./, expect_close = 1
[2003/10/15 13:32:15, 4] smbd/trans2.c:call_trans2findfirst(1023)
dptr_num is 256, wcard = *, attr = 22
[2003/10/15 13:32:15, 4] smbd/dir.c:dptr_close_internal(224)
closing dptr key 256
[2003/10/15 13:32:15, 4] smbd/trans2.c:call_trans2findfirst(1104)
SMBtrans2 mask=* directory=./ dirtype=22 numentries=10
[2003/10/15 13:32:15, 3] smbd/process.c:process_smb(890)
Transaction 6 of length 80
[2003/10/15 13:32:15, 3] smbd/process.c:switch_message(685)
switch message SMBtrans2 (pid 11995)
[2003/10/15 13:32:15, 4] smbd/uid.c:change_to_user(122)
change_to_user: Skipping user change - already user
[2003/10/15 13:32:15, 3] smbd/trans2.c:call_trans2qfilepathinfo(1901)
call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2003/10/15 13:32:15, 3] lib/util.c:unix_clean_name(580)
unix_clean_name []
[2003/10/15 13:32:15, 3] lib/util.c:unix_clean_name(580)
unix_clean_name [.]
[2003/10/15 13:32:15, 3] smbd/trans2.c:call_trans2qfilepathinfo(1929)
call_trans2qfilepathinfo . (fnum = -1) level=1004 call=5 total_data=0
[2003/10/15 13:33:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:34:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2003/10/15 13:35:15, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
Any help anyone could provide would be GREATLY appreciated. I'm at a
loss. Thanks.
Jason Pfingstmann
More information about the samba
mailing list