I upped the log level to 5 and restarted Samba. From Starbase I did a simple:

C:\> net use w: \\earth\web
Enter the user name for 'earth': System error 1223 has occurred.

The operation was canceled by the user.

The password is invalid for \\earth\web.

The logfile follows. It seems clear that it's confused as to which user is requesting access and I get mapped to nobody (but I'm not nobody! I'm not, I'm not, I'm not!).

[2005/01/17 20:23:31, 5] smbd/connection.c:claim_connection(156)
 claiming  0
[2005/01/17 20:23:31, 5] smbd/reply.c:reply_special(152)
 init msg_type=0x81 msg_flags=0x0
[2005/01/17 20:23:31, 3] smbd/process.c:process_smb(878)
 Transaction 1 of length 137
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(275)
 size=133
 smb_com=0x72
 smb_rcls=0
 smb_reh=0
 smb_err=0
 smb_flg=24
 smb_flg2=51283
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(281)
 smb_tid=0
 smb_pid=65279
 smb_uid=0
 smb_mid=0
 smt_wct=0
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(291)
 smb_bcc=98
[2005/01/17 20:23:31, 3] smbd/process.c:switch_message(685)
 switch message SMBnegprot (pid 15356)
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:set_sec_ctx(329)
 setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/17 20:23:31, 5] smbd/uid.c:change_to_root_user(217)
 change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/17 20:23:31, 3] smbd/negprot.c:reply_negprot(342)
 Requested protocol [PC NETWORK PROGRAM 1.0]
[2005/01/17 20:23:31, 3] smbd/negprot.c:reply_negprot(342)
 Requested protocol [LANMAN1.0]
[2005/01/17 20:23:31, 3] smbd/negprot.c:reply_negprot(342)
 Requested protocol [Windows for Workgroups 3.1a]
[2005/01/17 20:23:31, 3] smbd/negprot.c:reply_negprot(342)
 Requested protocol [LM1.2X002]
[2005/01/17 20:23:31, 3] smbd/negprot.c:reply_negprot(342)
 Requested protocol [LANMAN2.1]
[2005/01/17 20:23:31, 3] smbd/negprot.c:reply_negprot(342)
 Requested protocol [NT LM 0.12]
[2005/01/17 20:23:31, 3] smbd/negprot.c:reply_negprot(426)
 Selected protocol NT LM 0.12
[2005/01/17 20:23:31, 5] smbd/negprot.c:reply_negprot(433)
 negprot index=5
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(275)
 size=86
 smb_com=0x72
 smb_rcls=0
 smb_reh=0
 smb_err=0
 smb_flg=136
 smb_flg2=1
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(281)
 smb_tid=0
 smb_pid=65279
 smb_uid=0
 smb_mid=0
 smt_wct=17
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
 smb_vwv[0]=5 (0x5)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
 smb_vwv[1]=12803 (0x3203)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
 smb_vwv[2]=256 (0x100)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
 smb_vwv[3]=1024 (0x400)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
 smb_vwv[4]=65 (0x41)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)

smb_vwv[5]=0 (0x0)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[6]=256 (0x100)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[7]=64512 (0xFC00)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[8]=59 (0x3B)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[9]=63744 (0xF900)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[10]=227 (0xE3)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[11]=32768 (0x8000)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[12]=16227 (0x3F63)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[13]=5495 (0x1577)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[14]=50429 (0xC4FD)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[15]=57345 (0xE001)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[16]=2049 (0x801)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(291)
smb_bcc=17
[2005/01/17 20:23:31, 3] smbd/process.c:process_smb(878)
Transaction 2 of length 150
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(275)
size=146
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=18439
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(281)
smb_tid=0
smb_pid=65279
smb_uid=0
smb_mid=64
smt_wct=13
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[0]=117 (0x75)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[1]=115 (0x73)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[2]=16644 (0x4104)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[3]=50 (0x32)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[4]=0 (0x0)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[5]=15356 (0x3BFC)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[6]=0 (0x0)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[7]=1 (0x1)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[8]=0 (0x0)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[9]=0 (0x0)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[10]=0 (0x0)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[11]=212 (0xD4)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[12]=0 (0x0)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(291)
smb_bcc=54
[2005/01/17 20:23:31, 3] smbd/process.c:switch_message(685)
switch message SMBsesssetupX (pid 15356)
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/17 20:23:31, 5] smbd/uid.c:change_to_root_user(217)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/17 20:23:31, 3] smbd/reply.c:reply_sesssetup_and_X(858)
Domain=[] NativeOS=[Windows 2002 Service Pack 2 2600] NativeLanMan=[Windows 2002 5.1]
[2005/01/17 20:23:31, 3] smbd/reply.c:reply_sesssetup_and_X(868)
sesssetupX:name=[]
[2005/01/17 20:23:31, 4] lib/username.c:map_username(134)
Scanning username map /etc/samba/smbusers
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:push_sec_ctx(297)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2005/01/17 20:23:31, 3] smbd/uid.c:push_conn_ctx(286)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:get_current_groups(172)
get_current_groups: user is in 1 groups: 65534
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:pop_sec_ctx(436)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:get_current_groups(172)
get_current_groups: user is in 1 groups: 65534
[2005/01/17 20:23:31, 5] smbd/password.c:create_nt_token(254)
user token sid S-1-5-21-475808512-48502977-1500931650-132068
[2005/01/17 20:23:31, 5] smbd/password.c:create_nt_token(254)
user token sid S-1-5-21-475808512-48502977-1500931650-132069
[2005/01/17 20:23:31, 5] smbd/password.c:create_nt_token(254)
user token sid S-1-1-0
[2005/01/17 20:23:31, 5] smbd/password.c:create_nt_token(254)
user token sid S-1-5-2
[2005/01/17 20:23:31, 5] smbd/password.c:create_nt_token(254)
user token sid S-1-5-32-546
[2005/01/17 20:23:31, 3] smbd/password.c:register_vuid(336)
uid 65534 registered to name nobody
[2005/01/17 20:23:31, 3] smbd/password.c:register_vuid(338)
Clearing default real name
[2005/01/17 20:23:31, 3] smbd/password.c:register_vuid(340)
User name: nobody Real name: Nobody
[2005/01/17 20:23:31, 3] smbd/process.c:chain_reply(1023)
Chained message
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(275)
size=146
smb_com=0x75
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=18439
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(281)
smb_tid=0
smb_pid=65279
smb_uid=100
smb_mid=64
smt_wct=4
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[0]=255 (0xFF)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[1]=146 (0x92)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[2]=8 (0x8)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[3]=1 (0x1)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(291)
smb_bcc=20
[2005/01/17 20:23:31, 3] smbd/process.c:switch_message(685)
switch message SMBtconX (pid 15356)
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/17 20:23:31, 5] smbd/uid.c:change_to_root_user(217)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/17 20:23:31, 4] smbd/reply.c:reply_tcon_and_X(334)
Got device type ?????
[2005/01/17 20:23:31, 3] lib/access.c:check_access(318)
check_access: no hostnames in host allow/deny list.
[2005/01/17 20:23:31, 2] lib/access.c:check_access(329)
Allowed connection from (192.168.1.102)
[2005/01/17 20:23:31, 4] smbd/password.c:password_ok(593)
Null passwords not allowed.
[2005/01/17 20:23:31, 4] smbd/password.c:password_ok(593)
Null passwords not allowed.
[2005/01/17 20:23:31, 4] smbd/password.c:password_ok(593)
Null passwords not allowed.
[2005/01/17 20:23:31, 3] smbd/password.c:authorise_login(855)
authorise_login: ACCEPTED: guest account and guest ok (nobody)
[2005/01/17 20:23:31, 3] smbd/service.c:make_connection(487)
Connect path is /var/tmp
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:push_sec_ctx(297)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2005/01/17 20:23:31, 3] smbd/uid.c:push_conn_ctx(286)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:get_current_groups(172)
get_current_groups: user is in 1 groups: 65534
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:pop_sec_ctx(436)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:get_current_groups(172)
get_current_groups: user is in 1 groups: 65534
[2005/01/17 20:23:31, 5] smbd/connection.c:claim_connection(156)
claiming IPC$ 0
[2005/01/17 20:23:31, 5] smbd/password.c:create_nt_token(254)
user token sid S-1-5-21-475808512-48502977-1500931650-132068
[2005/01/17 20:23:31, 5] smbd/password.c:create_nt_token(254)
user token sid S-1-5-21-475808512-48502977-1500931650-132069
[2005/01/17 20:23:31, 5] smbd/password.c:create_nt_token(254)
user token sid S-1-1-0
[2005/01/17 20:23:31, 5] smbd/password.c:create_nt_token(254)
user token sid S-1-5-2
[2005/01/17 20:23:31, 5] smbd/password.c:create_nt_token(254)
user token sid S-1-5-32-546
[2005/01/17 20:23:31, 4] rpc_server/srv_srvsvc_nt.c:get_share_security(211)
get_share_security: using default secdesc for IPC$
[2005/01/17 20:23:31, 3] lib/util_seaccess.c:se_access_check(269)
se_access_check: user sid is S-1-5-21-475808512-48502977-1500931650-132068
[2005/01/17 20:23:31, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-21-475808512-48502977-1500931650-132069
[2005/01/17 20:23:31, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-1-0
[2005/01/17 20:23:31, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-2
[2005/01/17 20:23:31, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-32-546
[2005/01/17 20:23:31, 5] lib/util_seaccess.c:se_access_check(325)
se_access_check: access (2) granted.
[2005/01/17 20:23:31, 3] smbd/vfs.c:vfs_init_default(123)
Initialising default vfs hooks
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (65534, 65534) - sec_ctx_stack_ndx = 0
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:set_sec_ctx(334)
1 user groups:
65534
[2005/01/17 20:23:31, 5] smbd/uid.c:change_to_user(202)
change_to_user uid=(0,65534) gid=(0,65534)
[2005/01/17 20:23:31, 3] smbd/vfs.c:vfs_ChDir(574)
vfs_ChDir to /var/tmp
[2005/01/17 20:23:31, 3] smbd/service.c:make_connection(636)
starbase (192.168.1.102) connect to service IPC$ as user nobody (uid=65534, gid=65534) (pid 15356)
[2005/01/17 20:23:31, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/17 20:23:31, 5] smbd/uid.c:change_to_root_user(217)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/17 20:23:31, 3] smbd/reply.c:reply_tcon_and_X(395)
tconX service=ipc$ user=nobody
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(275)
size=105
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=136
smb_flg2=1
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(281)
smb_tid=1
smb_pid=65279
smb_uid=100
smb_mid=64
smt_wct=3
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[0]=117 (0x75)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[1]=88 (0x58)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(286)
smb_vwv[2]=1 (0x1)
[2005/01/17 20:23:31, 5] lib/util.c:show_msg(291)
smb_bcc=47
[2005/01/17 20:23:41, 3] smbd/process.c:process_smb(878)
Transaction 3 of length 43
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(275)
size=39
smb_com=0x74
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=18439
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(281)
smb_tid=0
smb_pid=65279
smb_uid=100
smb_mid=128
smt_wct=2
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(286)
smb_vwv[0]=255 (0xFF)
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(286)
smb_vwv[1]=0 (0x0)
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(291)
smb_bcc=0
[2005/01/17 20:23:41, 3] smbd/process.c:switch_message(685)
switch message SMBulogoffX (pid 15356)
[2005/01/17 20:23:41, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/17 20:23:41, 5] smbd/uid.c:change_to_root_user(217)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/17 20:23:41, 3] smbd/reply.c:reply_ulogoffX(1790)
ulogoffX vuid=100
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(275)
size=39
smb_com=0x74
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=136
smb_flg2=1
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(281)
smb_tid=0
smb_pid=65279
smb_uid=100
smb_mid=128
smt_wct=2
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(286)
smb_vwv[0]=255 (0xFF)
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(286)
smb_vwv[1]=0 (0x0)
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(291)
smb_bcc=0
[2005/01/17 20:23:41, 3] smbd/process.c:process_smb(878)
Transaction 4 of length 39
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(275)
size=35
smb_com=0x71
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=18439
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(281)
smb_tid=1
smb_pid=65279
smb_uid=100
smb_mid=192
smt_wct=0
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(291)
smb_bcc=0
[2005/01/17 20:23:41, 3] smbd/process.c:switch_message(685)
switch message SMBtdis (pid 15356)
[2005/01/17 20:23:41, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/17 20:23:41, 5] smbd/uid.c:change_to_root_user(217)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/17 20:23:41, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/17 20:23:41, 5] smbd/uid.c:change_to_root_user(217)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/17 20:23:41, 3] smbd/service.c:close_cnum(677)
starbase (192.168.1.102) closed connection to service IPC$
[2005/01/17 20:23:41, 3] smbd/connection.c:yield_connection(48)
Yielding connection to IPC$
[2005/01/17 20:23:41, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/17 20:23:41, 5] smbd/uid.c:change_to_root_user(217)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/17 20:23:41, 3] smbd/vfs.c:vfs_ChDir(574)
vfs_ChDir to /
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(275)
size=35
smb_com=0x71
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=136
smb_flg2=1
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(281)
smb_tid=1
smb_pid=65279
smb_uid=100
smb_mid=192
smt_wct=0
[2005/01/17 20:23:41, 5] lib/util.c:show_msg(291)
smb_bcc=0
[2005/01/17 20:23:41, 3] smbd/process.c:timeout_processing(1098)
end of file from client
[2005/01/17 20:23:41, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/17 20:23:41, 5] smbd/uid.c:change_to_root_user(217)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/17 20:23:41, 2] smbd/server.c:exit_server(461)
Closing connections
[2005/01/17 20:23:41, 3] smbd/connection.c:yield_connection(48)
Yielding connection to
[2005/01/17 20:23:41, 5] smbd/oplock.c:receive_local_message(108)
receive_local_message: doing select with timeout of 1 ms
[2005/01/17 20:23:41, 3] smbd/server.c:exit_server(495)
Server exit (normal exit)




--
God must love stupid people... He made so many.

--
To unsubscribe from this list go to the following URL and read the
instructions:  https://lists.samba.org/mailman/listinfo/samba

Reply via email to