[samba-jp:18819] 原因不明の認証エラー

UENO Satoshi satosi @ aqr.calmnet.ne.jp
2006年 8月 5日 (土) 08:48:08 JST



上野と申します。以前[samba-jp:18604]にて、Windows XPからのアクセス時に認
証エラーで接続できないケースが多発したという投稿をした者です。その投稿で
間違ったログを掲載していた事、その後もやはり解決できない事から、再度投稿
させて頂きます。

・環境

サーバはVine Linux Ver 3.2で、Sambaは3.0.10です。端末はWindows XP
Professional SP2/Home SP2です。ドメインは構成しておらず、同一のワークグ
ループに属しています。同一のネットワークで、間にルータなどはありません。
Sambaの他にサーバはありません。ADSL用モデムをルータ・DHCPサーバにしてい
ます。WINSやDNSサーバはありません。

以前はユーザレベルのセキュリティにしていたのですが、この問題が多発してか
ら共有レベルに落としています。その結果発生頻度はかなりおちましたが、依然
解決していません。サーバは24時間運用ではありません。

・現象

ユーザが端末からエクスプローラなどでSamba側につなげると、認証のダイアロ
グがあらわれます。正しいアカウントを入力しても、受け付けられず、ファイル
にアクセスできません。成功時と失敗時で端末側の設定に変更はありません。端
末側でファイアウォールやセキュリティソフトを切っても、結果は変わりません。

・発生のタイミング

確定的ではありませんが、1日間など、長時間稼動させた時に発生しやすいで
す。一箇所の端末で発生すると、五月雨式に他の端末でも起こります。ここで
Sambaデーモンのみ再起動させてやると、問題なく認証されてアクセスできます。

・解析

同一のマシンで認証に成功した時と失敗した時のログを比較してみました。ログ
レベルは100です。かなり長いので、失敗時のログのみ末尾に添付します。もし
よろしければ、かつ必要であれば、別途成功時のログも投稿させていただきます。

認証が成功と失敗に分かれるところから、遡って比較してみました。

 ・サーバのホスト(NetBIOS)名: GOSHUJIN
 ・ワークグループ名: TKY1
 ・クライアントのホスト(NetBIOS)名:ibm-8
 ・ユーザ名:onhcr

1.失敗時はSamba側でマップされたユーザ名に、ホスト名がついていない。

・失敗時
 [2006/06/14 14:01:40, 3] auth/auth.c:check_ntlm_password(222)
   check_ntlm_password:  mapped user is:[GOSHUJIN]\[onhcr]@[192.168.0.15]

・成功時
 [2006/06/12 11:12:44, 3] auth/auth.c:check_ntlm_password(222)
   check_ntlm_password:  mapped user is: []\[onhcr]@[192.168.0.15]


2.失敗時は事前にゲスト(nobody)でのアクセスを試みる

3.失敗時はpdb_set_domainがサーバ側ホスト名を示していない

4.失敗時はsesssetupXで端末側ホスト名・ユーザ名がぬけている。但し後ろの方
で再度sesssetupXで始まる手続きがあり、そこには入っている(しかし、その後
のpdb_set_domainではやはりサーバ側ホスト名を示していない)。

以上です。足らない情報などありましたら、ご指摘下さい。どのような小さなこ
とでも結構です。どうぞよろしくお願いいたします。

・設定ファイル

[global]
	#config file = smb.conf.%m
	dos charset = cp932
	unix charset = eucJP-ms
	display charset = eucJP-ms
	workgroup = TKY1
	netbios name =
	#server string = your pc is %M or %m
	server string = file server
	security = SHARE
	passwd program = /usr/bin/passwd %u
	passwd chat = *New*password* %n\n *ReType*new*password* %n\n
*passwd:*all*authentication*tokens*updated*successfully*
	username map = /etc/samba/smbusers
	unix password sync = Yes
	log level = 100
	log file = /var/log/samba/%m.log
	max log size = 500
	socket options = TCP_NODELAY SO_RCVBUF=8192 SO_SNDBUF=8192
	load printers = No
	os level = 20
	dns proxy = No
	ldap ssl = no
	utmp = Yes
	create mask = 0774
	directory mask = 0775
	hosts allow = 192.168.0.
	wins support = Yes
	include = smb.conf.%m

[template_share]
	available = No
	read only = No
	vfs objects = recycle
	recycle:maxsize = 2000000
	recycle:exclude = *.TMP

[Staff_Shared]
	path = /mnt/share/staff_shared
	valid users = staff, onhcr
	copy = template_share
	available = Yes

[Staff_Intern_Shared]
	path = /mnt/share/staff_intern_shared
	valid users = staff, intern, onhcr
	copy = template_share
	available = Yes

[ONHCR_related]
	path = /mnt/share/onhcr_related
	valid users = onhcr
	copy = template_share
	available = Yes

[Guest_Shared]
	valid users = staff, intern, onhcr
	copy = template_share
	available = Yes
	path = /mnt/share/guest_shared


・失敗時ログ

[2006/06/12 11:12:42, 6] param/loadparm.c:lp_file_list_changed(2689)
  lp_file_list_changed()
  file smb.conf.%m -> smb.conf.192.168.0.15  last mod_time: Thu Jan  1
09:00:00 1970

  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed
Jun  7 21:38:51 2006

[2006/06/12 11:12:42, 3] smbd/oplock.c:init_oplocks(1302)
  open_oplock_ipc: opening loopback UDP socket.
[2006/06/12 11:12:42, 10] lib/util_sock.c:open_socket_in(717)
  bind succeeded on port 0
[2006/06/12 11:12:42, 3] smbd/oplock_linux.c:linux_init_kernel_oplocks(303)
  Linux kernel oplocks enabled
[2006/06/12 11:12:42, 3] smbd/oplock.c:init_oplocks(1333)
  open_oplock ipc: pid = 1434, global_oplock_port = 32792
[2006/06/12 11:12:42, 4] lib/time.c:get_serverzone(122)
  Serverzone is -32400
[2006/06/12 11:12:42, 10]
lib/util_sock.c:read_smb_length_return_keepalive(505)
  got smb length of 68
[2006/06/12 11:12:42, 3] lib/access.c:check_access(313)
  check_access: no hostnames in host allow/deny list.
[2006/06/12 11:12:42, 2] lib/access.c:check_access(324)
  Allowed connection from  (192.168.0.15)
[2006/06/12 11:12:42, 6] smbd/process.c:process_smb(1090)
  got message type 0x81 of len 0x44
[2006/06/12 11:12:42, 3] smbd/process.c:process_smb(1091)
  Transaction 0 of length 72
[2006/06/12 11:12:42, 2] smbd/reply.c:reply_special(235)
  netbios connect: name1=GOSHUJIN        name2=IBM-8
[2006/06/12 11:12:42, 2] smbd/reply.c:reply_special(242)
  netbios connect: local=goshujin remote=ibm-8, name type = 0
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam(293)
  Finding user ibm-8
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam_internals(223)
  Trying _Get_Pwnam(), username as lowercase is ibm-8
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam_internals(239)
  Trying _Get_Pwnam(), username as uppercase is IBM-8
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam_internals(247)
  Checking combinations of 0 uppercase letters in ibm-8
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam_internals(251)
  Get_Pwnam_internals didn't find user [ibm-8]!
[2006/06/12 11:12:42, 6] param/loadparm.c:lp_file_list_changed(2689)
  lp_file_list_changed()
  file smb.conf.%m -> smb.conf.192.168.0.15  last mod_time: Thu Jan  1
09:00:00 1970

  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed
Jun  7 21:38:51 2006

[2006/06/12 11:12:42, 3] smbd/oplock.c:init_oplocks(1302)
  open_oplock_ipc: opening loopback UDP socket.
[2006/06/12 11:12:42, 10] lib/util_sock.c:open_socket_in(717)
  bind succeeded on port 0
[2006/06/12 11:12:42, 3] smbd/oplock_linux.c:linux_init_kernel_oplocks(303)
  Linux kernel oplocks enabled
[2006/06/12 11:12:42, 3] smbd/oplock.c:init_oplocks(1333)
  open_oplock ipc: pid = 1435, global_oplock_port = 32793
[2006/06/12 11:12:42, 4] lib/time.c:get_serverzone(122)
  Serverzone is -32400
[2006/06/12 11:12:42, 10]
lib/util_sock.c:read_smb_length_return_keepalive(505)
  got smb length of 133
[2006/06/12 11:12:42, 3] lib/access.c:check_access(313)
  check_access: no hostnames in host allow/deny list.
[2006/06/12 11:12:42, 2] lib/access.c:check_access(324)
  Allowed connection from  (192.168.0.15)
[2006/06/12 11:12:42, 6] smbd/process.c:process_smb(1090)
  got message type 0x0 of len 0x85
[2006/06/12 11:12:42, 3] smbd/process.c:process_smb(1091)
  Transaction 0 of length 137
[2006/06/12 11:12:42, 5] lib/util.c:show_msg(486)
[2006/06/12 11:12:42, 5] lib/util.c:show_msg(496)
  size=133
  smb_com=0x72
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51283
  smb_tid=0
  smb_pid=65279
  smb_uid=0
  smb_mid=0
  smt_wct=0
  smb_bcc=98
[2006/06/12 11:12:42, 10] lib/util.c:dump_data(1999)
  [000] 02 50 43 20 4E 45 54 57  4F 52 4B 20 50 52 4F 47  .PC NETW ORK PROG
  [010] 52 41 4D 20 31 2E 30 00  02 4C 41 4E 4D 41 4E 31  RAM 1.0. .LANMAN1
  [020] 2E 30 00 02 57 69 6E 64  6F 77 73 20 66 6F 72 20  .0..Wind ows for
  [030] 57 6F 72 6B 67 72 6F 75  70 73 20 33 2E 31 61 00  Workgrou ps 3.1a.
  [040] 02 4C 4D 31 2E 32 58 30  30 32 00 02 4C 41 4E 4D  .LM1.2X0 02..LANM
  [050] 41 4E 32 2E 31 00 02 4E  54 20 4C 4D 20 30 2E 31  AN2.1..N T LM 0.1
  [060] 32 00                                             2.
[2006/06/12 11:12:42, 3] smbd/process.c:switch_message(886)
  switch message SMBnegprot (pid 1435) conn 0x0
[2006/06/12 11:12:42, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2006/06/12 11:12:42, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2006/06/12 11:12:42, 3] smbd/negprot.c:reply_negprot(461)
  Requested protocol [PC NETWORK PROGRAM 1.0]
[2006/06/12 11:12:42, 3] smbd/negprot.c:reply_negprot(461)
  Requested protocol [LANMAN1.0]
[2006/06/12 11:12:42, 3] smbd/negprot.c:reply_negprot(461)
  Requested protocol [Windows for Workgroups 3.1a]
[2006/06/12 11:12:42, 3] smbd/negprot.c:reply_negprot(461)
  Requested protocol [LM1.2X002]
[2006/06/12 11:12:42, 3] smbd/negprot.c:reply_negprot(461)
  Requested protocol [LANMAN2.1]
[2006/06/12 11:12:42, 3] smbd/negprot.c:reply_negprot(461)
  Requested protocol [NT LM 0.12]
[2006/06/12 11:12:42, 10] lib/util.c:set_remote_arch(1974)
  set_remote_arch: Client arch is 'Win2K'
[2006/06/12 11:12:42, 6] param/loadparm.c:lp_file_list_changed(2689)
  lp_file_list_changed()
  file smb.conf.%m -> smb.conf.192.168.0.15  last mod_time: Thu Jan  1
09:00:00 1970

  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed
Jun  7 21:38:51 2006

[2006/06/12 11:12:42, 6] param/loadparm.c:lp_file_list_changed(2689)
  lp_file_list_changed()
  file smb.conf.%m -> smb.conf.192.168.0.15  last mod_time: Thu Jan  1
09:00:00 1970

  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed
Jun  7 21:38:51 2006

[2006/06/12 11:12:42, 10] smbd/negprot.c:get_challenge(40)
  get challenge: creating negprot_global_auth_context
[2006/06/12 11:12:42, 5] auth/auth.c:make_auth_context_subsystem(490)
  Making default auth method list for security=share, encrypt passwords
= yes
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(45)
  Attempting to register auth backend rhosts
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(57)
  Successfully added auth method 'rhosts'
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(45)
  Attempting to register auth backend hostsequiv
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(57)
  Successfully added auth method 'hostsequiv'
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(45)
  Attempting to register auth backend sam
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(57)
  Successfully added auth method 'sam'
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(45)
  Attempting to register auth backend sam_ignoredomain
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(57)
  Successfully added auth method 'sam_ignoredomain'
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(45)
  Attempting to register auth backend unix
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(57)
  Successfully added auth method 'unix'
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(45)
  Attempting to register auth backend winbind
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(57)
  Successfully added auth method 'winbind'
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(45)
  Attempting to register auth backend smbserver
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(57)
  Successfully added auth method 'smbserver'
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(45)
  Attempting to register auth backend trustdomain
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(57)
  Successfully added auth method 'trustdomain'
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(45)
  Attempting to register auth backend ntdomain
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(57)
  Successfully added auth method 'ntdomain'
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(45)
  Attempting to register auth backend guest
[2006/06/12 11:12:42, 5] auth/auth.c:smb_register_auth(57)
  Successfully added auth method 'guest'
[2006/06/12 11:12:42, 5] auth/auth.c:load_auth_module(384)
  load_auth_module: Attempting to find an auth method to match guest
[2006/06/12 11:12:42, 5] auth/auth.c:load_auth_module(409)
  load_auth_module: auth method guest has a valid init
[2006/06/12 11:12:42, 5] auth/auth.c:load_auth_module(384)
  load_auth_module: Attempting to find an auth method to match sam
[2006/06/12 11:12:42, 5] auth/auth.c:load_auth_module(409)
  load_auth_module: auth method sam has a valid init
[2006/06/12 11:12:42, 10] smbd/negprot.c:get_challenge(45)
  get challenge: getting challenge
[2006/06/12 11:12:42, 5] auth/auth.c:get_ntlm_challenge(95)
  auth_get_challenge: module guest did not want to specify a challenge
[2006/06/12 11:12:42, 5] auth/auth.c:get_ntlm_challenge(95)
  auth_get_challenge: module sam did not want to specify a challenge
[2006/06/12 11:12:42, 5] auth/auth.c:get_ntlm_challenge(135)
  auth_context challenge created by random
[2006/06/12 11:12:42, 5] auth/auth.c:get_ntlm_challenge(136)
  challenge is:
[2006/06/12 11:12:42, 5] lib/util.c:dump_data(1999)
  [000] 68 AD 37 EC 5F EF 86 C1                           h.7._...
[2006/06/12 11:12:42, 3] smbd/negprot.c:reply_nt1(327)
  not using SPNEGO
[2006/06/12 11:12:42, 3] smbd/negprot.c:reply_negprot(549)
  Selected protocol NT LM 0.12
[2006/06/12 11:12:42, 5] smbd/negprot.c:reply_negprot(555)
  negprot index=5
[2006/06/12 11:12:42, 5] lib/util.c:show_msg(486)
[2006/06/12 11:12:42, 5] lib/util.c:show_msg(496)
  size=87
  smb_com=0x72
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=49153
  smb_tid=0
  smb_pid=65279
  smb_uid=0
  smb_mid=0
  smt_wct=17
  smb_vwv[ 0]=    5 (0x5)
  smb_vwv[ 1]=12802 (0x3202)
  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]=39680 (0x9B00)
  smb_vwv[ 8]=    5 (0x5)
  smb_vwv[ 9]=64768 (0xFD00)
  smb_vwv[10]=32995 (0x80E3)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=36233 (0x8D89)
  smb_vwv[13]=50607 (0xC5AF)
  smb_vwv[14]=50829 (0xC68D)
  smb_vwv[15]=58369 (0xE401)
  smb_vwv[16]= 2301 (0x8FD)
  smb_bcc=18
[2006/06/12 11:12:42, 10] lib/util.c:dump_data(1999)
  [000] 68 AD 37 EC 5F EF 86 C1  54 00 4B 00 59 00 31 00  h.7._... T.K.Y.1.
  [010] 00 00                                             ..
[2006/06/12 11:12:42, 6] lib/util_sock.c:write_socket(449)
  write_socket(5,91)
[2006/06/12 11:12:42, 6] lib/util_sock.c:write_socket(452)
  write_socket(5,91) wrote 91
[2006/06/12 11:12:42, 10]
lib/util_sock.c:read_smb_length_return_keepalive(505)
  got smb length of 220
[2006/06/12 11:12:42, 6] smbd/process.c:process_smb(1090)
  got message type 0x0 of len 0xdc
[2006/06/12 11:12:42, 3] smbd/process.c:process_smb(1091)
  Transaction 1 of length 224
[2006/06/12 11:12:42, 5] lib/util.c:show_msg(486)
[2006/06/12 11:12:42, 5] lib/util.c:show_msg(496)
  size=220
  smb_com=0x73
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=0
  smb_pid=65279
  smb_uid=0
  smb_mid=64
  smt_wct=13
  smb_vwv[ 0]=  117 (0x75)
  smb_vwv[ 1]=  170 (0xAA)
  smb_vwv[ 2]=16644 (0x4104)
  smb_vwv[ 3]=   50 (0x32)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]= 1435 (0x59B)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    1 (0x1)
  smb_vwv[ 8]=    1 (0x1)
  smb_vwv[ 9]=    0 (0x0)
  smb_vwv[10]=    0 (0x0)
  smb_vwv[11]=  212 (0xD4)
  smb_vwv[12]=    0 (0x0)
  smb_bcc=109
[2006/06/12 11:12:42, 10] lib/util.c:dump_data(1999)
  [000] 00 00 00 00 00 00 00 57  00 69 00 6E 00 64 00 6F  .......W .i.n.d.o
  [010] 00 77 00 73 00 20 00 32  00 30 00 30 00 32 00 20  .w.s. .2 .0.0.2.
  [020] 00 53 00 65 00 72 00 76  00 69 00 63 00 65 00 20  .S.e.r.v .i.c.e.
  [030] 00 50 00 61 00 63 00 6B  00 20 00 32 00 20 00 32  .P.a.c.k . .2. .2
  [040] 00 36 00 30 00 30 00 00  00 57 00 69 00 6E 00 64  .6.0.0.. .W.i.n.d
  [050] 00 6F 00 77 00 73 00 20  00 32 00 30 00 30 00 32  .o.w.s.  .2.0.0.2
  [060] 00 20 00 35 00 2E 00 31  00 00 00 00 00           . .5...1 .....
[2006/06/12 11:12:42, 3] smbd/process.c:switch_message(886)
  switch message SMBsesssetupX (pid 1435) conn 0x0
[2006/06/12 11:12:42, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2006/06/12 11:12:42, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2006/06/12 11:12:42, 3] smbd/sesssetup.c:reply_sesssetup_and_X(655)
  wct=13 flg2=0xc807
[2006/06/12 11:12:42, 3] smbd/sesssetup.c:reply_sesssetup_and_X(789)
  Domain=[]  NativeOS=[Windows 2002 Service Pack 2 2600]
NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
[2006/06/12 11:12:42, 10] lib/util.c:set_remote_arch(1974)
  set_remote_arch: Client arch is 'WinXP'
[2006/06/12 11:12:42, 2] smbd/sesssetup.c:setup_new_vc_session(608)
  setup_new_vc_session: New VC == 0, if NT4.x compatible we would close
all old resources.
[2006/06/12 11:12:42, 3] smbd/sesssetup.c:reply_sesssetup_and_X(804)
  sesssetupX:name=[]\[]@[192.168.0.15]
[2006/06/12 11:12:42, 6] param/loadparm.c:lp_file_list_changed(2689)
  lp_file_list_changed()
  file smb.conf.%m -> smb.conf.192.168.0.15  last mod_time: Thu Jan  1
09:00:00 1970

  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed
Jun  7 21:38:51 2006

[2006/06/12 11:12:42, 4] lib/username.c:map_username(132)
  Scanning username map /etc/samba/smbusers
[2006/06/12 11:12:42, 10] lib/username.c:user_in_list(529)
  user_in_list: checking user nobody in list
[2006/06/12 11:12:42, 10] lib/username.c:user_in_list(533)
  user_in_list: checking user |nobody| against |administrator|
[2006/06/12 11:12:42, 10] lib/username.c:user_in_list(533)
  user_in_list: checking user |nobody| against |admin|
[2006/06/12 11:12:42, 10] lib/username.c:user_in_list(529)
  user_in_list: checking user nobody in list
[2006/06/12 11:12:42, 10] lib/username.c:user_in_list(533)
  user_in_list: checking user |nobody| against |guest|
[2006/06/12 11:12:42, 10] lib/username.c:user_in_list(533)
  user_in_list: checking user |nobody| against |pcguest|
[2006/06/12 11:12:42, 10] lib/username.c:user_in_list(533)
  user_in_list: checking user |nobody| against |smbguest|
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam(293)
  Finding user nobody
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam_internals(223)
  Trying _Get_Pwnam(), username as lowercase is nobody
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam_internals(251)
  Get_Pwnam_internals did find user [nobody]!
[2006/06/12 11:12:42, 3] smbd/sesssetup.c:check_guest_password(116)
  Got anonymous request
[2006/06/12 11:12:42, 5] auth/auth.c:make_auth_context_subsystem(490)
  Making default auth method list for security=share, encrypt passwords
= yes
[2006/06/12 11:12:42, 5] auth/auth.c:load_auth_module(384)
  load_auth_module: Attempting to find an auth method to match guest
[2006/06/12 11:12:42, 5] auth/auth.c:load_auth_module(409)
  load_auth_module: auth method guest has a valid init
[2006/06/12 11:12:42, 5] auth/auth.c:load_auth_module(384)
  load_auth_module: Attempting to find an auth method to match sam
[2006/06/12 11:12:42, 5] auth/auth.c:load_auth_module(409)
  load_auth_module: auth method sam has a valid init
[2006/06/12 11:12:42, 5] auth/auth_util.c:make_user_info(133)
  attempting to make a user_info for  ()
[2006/06/12 11:12:42, 5] auth/auth_util.c:make_user_info(143)
  making strings for 's user_info struct
[2006/06/12 11:12:42, 5] auth/auth_util.c:make_user_info(185)
  making blobs for 's user_info struct
[2006/06/12 11:12:42, 10] auth/auth_util.c:make_user_info(201)
  made an encrypted user_info for  ()
[2006/06/12 11:12:42, 3] auth/auth.c:check_ntlm_password(219)
  check_ntlm_password:  Checking password for unmapped user []\[]@[]
with the new password interface
[2006/06/12 11:12:42, 3] auth/auth.c:check_ntlm_password(222)
  check_ntlm_password:  mapped user is: []\[]@[]
[2006/06/12 11:12:42, 10] auth/auth.c:check_ntlm_password(231)
  check_ntlm_password: auth_context challenge created by fixed
[2006/06/12 11:12:42, 10] auth/auth.c:check_ntlm_password(233)
  challenge is:
[2006/06/12 11:12:42, 5] lib/util.c:dump_data(1999)
  [000] 00 00 00 00 00 00 00 00                           ........
[2006/06/12 11:12:42, 3] smbd/sec_ctx.c:push_sec_ctx(256)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2006/06/12 11:12:42, 3] smbd/uid.c:push_conn_ctx(365)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2006/06/12 11:12:42, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2006/06/12 11:12:42, 10] passdb/pdb_smbpasswd.c:smbpasswd_getsampwsid(1367)
  smbpasswd_getsampwrid: search by sid:
S-1-5-21-785178855-2583564708-3681729009-501
[2006/06/12 11:12:42, 10] passdb/pdb_smbpasswd.c:smbpasswd_getsampwnam(1318)
  getsampwnam (smbpasswd): search by name: nobody
[2006/06/12 11:12:42, 10] passdb/pdb_smbpasswd.c:startsmbfilepwent(184)
  startsmbfilepwent_internal: opening file /etc/samba/smbpasswd
[2006/06/12 11:12:42, 5] passdb/pdb_smbpasswd.c:getsmbfilepwent(486)
  getsmbfilepwent: returning passwd entry for user staff, uid 501
[2006/06/12 11:12:42, 5] passdb/pdb_smbpasswd.c:getsmbfilepwent(486)
  getsmbfilepwent: returning passwd entry for user onhcr, uid 503
[2006/06/12 11:12:42, 10] passdb/pdb_smbpasswd.c:getsmbfilepwent(460)
  getsmbfilepwent: LM password for user nobody invalidated
[2006/06/12 11:12:42, 5] passdb/pdb_smbpasswd.c:getsmbfilepwent(486)
  getsmbfilepwent: returning passwd entry for user nobody, uid 99
[2006/06/12 11:12:42, 7] passdb/pdb_smbpasswd.c:endsmbfilepwent(301)
  endsmbfilepwent_internal: closed password file.
[2006/06/12 11:12:42, 10] passdb/pdb_smbpasswd.c:smbpasswd_getsampwnam(1340)
  getsampwnam (smbpasswd): found by name: nobody
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_username(625)
  pdb_set_username: setting username nobody, was
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_init_flags(525)
  element 12 -> now SET
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_fullname(706)
  pdb_set_full_name: setting full name Nobody, was
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_init_flags(525)
  element 13 -> now SET
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_unix_homedir(841)
  pdb_set_unix_homedir: setting home dir /, was NULL
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_init_flags(525)
  element 22 -> now SET
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_domain(652)
  pdb_set_domain: setting domain , was
[2006/06/12 11:12:42, 11] passdb/pdb_get_set.c:pdb_set_init_flags(537)
  element 14 -> now DEFAULT
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_user_sid(552)
  pdb_set_user_sid: setting user sid
S-1-5-21-785178855-2583564708-3681729009-501
[2006/06/12 11:12:42, 11] passdb/pdb_get_set.c:pdb_set_init_flags(537)
  element 18 -> now DEFAULT
[2006/06/12 11:12:42, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(73)
  pdb_set_user_sid_from_rid:
  	setting user sid S-1-5-21-785178855-2583564708-3681729009-501 from
rid 501
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_group_sid(588)
  pdb_set_group_sid: setting group sid
S-1-5-21-785178855-2583564708-3681729009-514
[2006/06/12 11:12:42, 11] passdb/pdb_get_set.c:pdb_set_init_flags(537)
  element 19 -> now DEFAULT
[2006/06/12 11:12:42, 10]
passdb/pdb_compat.c:pdb_set_group_sid_from_rid(100)
  pdb_set_group_sid_from_rid:
  	setting group sid S-1-5-21-785178855-2583564708-3681729009-514 from
rid 514
[2006/06/12 11:12:42, 4] lib/substitute.c:automount_server(323)
  Home server: goshujin
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_profile_path(760)
  pdb_set_profile_path: setting profile path \\goshujin\nobody\profile, was
[2006/06/12 11:12:42, 11] passdb/pdb_get_set.c:pdb_set_init_flags(537)
  element 2 -> now DEFAULT
[2006/06/12 11:12:42, 4] lib/substitute.c:automount_server(323)
  Home server: goshujin
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_homedir(814)
  pdb_set_homedir: setting home dir \\goshujin\nobody, was
[2006/06/12 11:12:42, 11] passdb/pdb_get_set.c:pdb_set_init_flags(537)
  element 1 -> now DEFAULT
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(787)
  pdb_set_dir_drive: setting dir drive , was NULL
[2006/06/12 11:12:42, 11] passdb/pdb_get_set.c:pdb_set_init_flags(537)
  element 3 -> now DEFAULT
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_logon_script(733)
  pdb_set_logon_script: setting logon script , was
[2006/06/12 11:12:42, 11] passdb/pdb_get_set.c:pdb_set_init_flags(537)
  element 4 -> now DEFAULT
[2006/06/12 11:12:42, 11] passdb/pdb_get_set.c:pdb_set_init_flags(537)
  element 20 -> now DEFAULT
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_init_flags(525)
  element 33 -> now SET
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_init_flags(525)
  element 32 -> now SET
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_init_flags(525)
  element 20 -> now SET
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_init_flags(525)
  element 21 -> now SET
[2006/06/12 11:12:42, 10] passdb/pdb_get_set.c:pdb_set_init_flags(525)
  element 9 -> now SET
[2006/06/12 11:12:42, 11] passdb/pdb_get_set.c:pdb_get_init_flags(210)
  element 21: SET
[2006/06/12 11:12:42, 11] passdb/pdb_get_set.c:pdb_get_init_flags(210)
  element 32: SET
[2006/06/12 11:12:42, 11] passdb/pdb_get_set.c:pdb_get_init_flags(210)
  element 33: SET
[2006/06/12 11:12:42, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2006/06/12 11:12:42, 10] lib/system_smbd.c:sys_getgrouplist(117)
  sys_getgrouplist: user [nobody]
[2006/06/12 11:12:42, 10] lib/system_smbd.c:sys_getgrouplist(126)
  sys_getgrouplist(): disabled winbindd for group lookup [user == nobody]
[2006/06/12 11:12:42, 3] smbd/sec_ctx.c:push_sec_ctx(256)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2006/06/12 11:12:42, 3] smbd/uid.c:push_conn_ctx(365)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2006/06/12 11:12:42, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2006/06/12 11:12:42, 8] lib/util_getent.c:remove_duplicate_gids(330)
  remove_duplicate_gids: Enter 2 gids
[2006/06/12 11:12:42, 8] lib/util_getent.c:remove_duplicate_gids(348)
  remove_duplicate_gids: Exit 1 gids
[2006/06/12 11:12:42, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 99
  Primary group is 99 and contains 1 supplementary groups
  Group[  0]: 99
[2006/06/12 11:12:42, 3] smbd/sec_ctx.c:push_sec_ctx(256)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2006/06/12 11:12:42, 3] smbd/uid.c:push_conn_ctx(365)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2006/06/12 11:12:42, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2006/06/12 11:12:42, 18] tdb/tdbutil.c:tdb_pack(472)
  tdb_pack(ddff, 1024) -> 24
(以下同様の行を中略)

[2006/06/12 11:12:42, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2006/06/12 11:12:42, 10] passdb/passdb.c:local_gid_to_sid(1264)
  local_gid_to_sid: Fall back to algorithmic mapping: 99 -> S-0-0
[2006/06/12 11:12:42, 8] passdb/passdb.c:algorithmic_gid_to_sid(1230)
  algorithmic_gid_to_sid: falling back to RID algorithm
[2006/06/12 11:12:42, 10] passdb/passdb.c:algorithmic_gid_to_sid(1234)
  algorithmic_gid_to_sid:  gid (99) -> SID
S-1-5-21-785178855-2583564708-3681729009-1199.
[2006/06/12 11:12:42, 10] passdb/lookup_sid.c:gid_to_sid(365)
  gid_to_sid: local 99 -> S-1-5-21-785178855-2583564708-3681729009-1199
[2006/06/12 11:12:42, 10] auth/auth_util.c:debug_nt_user_token(491)
  NT user token of user S-1-5-21-785178855-2583564708-3681729009-501
  contains 6 SIDs
  SID[  0]: S-1-5-21-785178855-2583564708-3681729009-501
  SID[  1]: S-1-5-21-785178855-2583564708-3681729009-514
  SID[  2]: S-1-1-0
  SID[  3]: S-1-5-2
  SID[  4]: S-1-5-32-546
  SID[  5]: S-1-5-21-785178855-2583564708-3681729009-1199
[2006/06/12 11:12:42, 5] auth/auth_util.c:make_server_info_sam(844)
  make_server_info_sam: made server info for user nobody -> nobody
[2006/06/12 11:12:42, 3] auth/auth.c:check_ntlm_password(268)
  check_ntlm_password: guest authentication for user [] succeeded
[2006/06/12 11:12:42, 5] auth/auth.c:check_ntlm_password(305)
  check_ntlm_password:  guest authentication for user [] -> [] ->
[nobody] succeeded
[2006/06/12 11:12:42, 5] auth/auth_util.c:free_user_info(1318)
  attempting to free (and zero) a user_info structure
[2006/06/12 11:12:42, 10] auth/auth_util.c:free_user_info(1321)
  structure was created for
[2006/06/12 11:12:42, 5] auth/auth_util.c:free_user_info(1318)
  attempting to free (and zero) a user_info structure
[2006/06/12 11:12:42, 6] param/loadparm.c:lp_file_list_changed(2689)
  lp_file_list_changed()
  file smb.conf.%m -> smb.conf.192.168.0.15  last mod_time: Thu Jan  1
09:00:00 1970

  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Wed
Jun  7 21:38:51 2006

[2006/06/12 11:12:42, 3] smbd/process.c:chain_reply(1232)
  Chained message
[2006/06/12 11:12:42, 5] lib/util.c:show_msg(486)
[2006/06/12 11:12:42, 5] lib/util.c:show_msg(496)
  size=220
  smb_com=0x75
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=0
  smb_pid=65279
  smb_uid=0
  smb_mid=64
  smt_wct=4
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=  220 (0xDC)
  smb_vwv[ 2]=    8 (0x8)
  smb_vwv[ 3]=    0 (0x0)
  smb_bcc=39
[2006/06/12 11:12:42, 10] lib/util.c:dump_data(1999)
  [000] 00 5C 00 5C 00 47 00 4F  00 53 00 48 00 55 00 4A  .\.\.G.O .S.H.U.J
  [010] 00 49 00 4E 00 5C 00 49  00 50 00 43 00 24 00 00  .I.N.\.I .P.C.$..
  [020] 00 3F 3F 3F 3F 3F 00                              .?????.
[2006/06/12 11:12:42, 3] smbd/process.c:switch_message(886)
  switch message SMBtconX (pid 1435) conn 0x0
[2006/06/12 11:12:42, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2006/06/12 11:12:42, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2006/06/12 11:12:42, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2006/06/12 11:12:42, 4] smbd/reply.c:reply_tcon_and_X(408)
  Client requested device type [] for share [IPC$]
[2006/06/12 11:12:42, 5] smbd/service.c:make_connection(813)
  making a connection to 'normal' service ipc$
[2006/06/12 11:12:42, 3] lib/access.c:check_access(313)
  check_access: no hostnames in host allow/deny list.
[2006/06/12 11:12:42, 2] lib/access.c:check_access(324)
  Allowed connection from  (192.168.0.15)
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam(293)
  Finding user IPC$
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam_internals(223)
  Trying _Get_Pwnam(), username as lowercase is ipc$
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam_internals(230)
  Trying _Get_Pwnam(), username as given is IPC$
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam_internals(247)
  Checking combinations of 0 uppercase letters in ipc$
[2006/06/12 11:12:42, 5] lib/username.c:Get_Pwnam_internals(251)
  Get_Pwnam_internals didn't find user [IPC$]!
[2006/06/12 11:12:42, 5] auth/auth.c:make_auth_context_subsystem(490)
  Making default auth method list for security=share, encrypt passwords
= yes
[2006/06/12 11:12:42, 5] auth/auth.c:load_auth_module(384)
  load_auth_module: Attempting to find an auth method to match guest
[2006/06/12 11:12:42, 5] auth/auth.c:load_auth_module(409)
  load_auth_module: auth method guest has a valid init
[2006/06/12 11:12:42, 5] auth/auth.c:load_auth_module(384)
  load_auth_module: Attempting to find an auth method to match sam
[2006/06/12 11:12:42, 5] auth/auth.c:load_auth_module(409)
  load_auth_module: auth method sam has a valid init
[2006/06/12 11:12:42, 5] auth/auth.c:get_ntlm_challenge(95)
  auth_get_challenge: module guest did not want to specify a challenge
[2006/06/12 11:12:42, 5] auth/auth.c:get_ntlm_challenge(95)
  auth_get_challenge: module sam did not want to specify a challenge
[2006/06/12 11:12:42, 5] auth/auth.c:get_ntlm_challenge(135)
  auth_context challenge created by random
[2006/06/12 11:12:42, 5] auth/auth.c:get_ntlm_challenge(136)
  challenge is:
[2006/06/12 11:12:42, 5] lib/util.c:dump_data(1999)
  [000] D8 B9 05 C5 73 83 F8 2B                           ....s..+
[2006/06/12 11:12:42, 5] auth/auth_util.c:make_user_info_for_reply(403)
  make_user_info_for_reply: User passwords not in encrypted format.
[2006/06/12 11:12:42, 5] auth/auth_util.c:make_user_info_map(225)
  make_user_info_map: Mapping user [TKY1]\[nobody] from workstation
[192.168.0.15]
[2006/06/12 11:12:42, 5] auth/auth_util.c:make_user_info(133)
  attempting to make a user_info for nobody (nobody)
[2006/06/12 11:12:42, 5] auth/auth_util.c:make_user_info(143)
  making strings for nobody's user_info struct
[2006/06/12 11:12:42, 5] auth/auth_util.c:make_user_info(185)
  making blobs for nobody's user_info struct
[2006/06/12 11:12:42, 10] auth/auth_util.c:make_user_info(201)
  made an unencrypted user_info for nobody (nobody)
[2006/06/12 11:12:42, 3] auth/auth.c:check_ntlm_password(219)
  check_ntlm_password:  Checking password for unmapped user
[TKY1]\[nobody]@[192.168.0.15] with the new password interface
[2006/06/12 11:12:42, 3] auth/auth.c:check_ntlm_password(222)
  check_ntlm_password:  mapped user is: []\[nobody]@[192.168.0.15]
[2006/06/12 11:12:42, 10] auth/auth.c:check_ntlm_password(231)
  check_ntlm_password: auth_context challenge created by random
[2006/06/12 11:12:42, 10] auth/auth.c:check_ntlm_password(233)
  challenge is:
[2006/06/12 11:12:42, 5] lib/util.c:dump_data(1999)
  [000] D8 B9 05 C5 73 83 F8 2B                           ....s..+
[2006/06/12 11:12:42, 10] auth/auth.c:check_ntlm_password(259)
  check_ntlm_password: guest had nothing to say
[2006/06/12 11:12:42, 8] lib/util.c:is_myname(1819)
  is_myname("") returns 0
[2006/06/12 11:12:42, 6] auth/auth_sam.c:check_samstrict_security(358)
  check_samstrict_security:  is not one of my local names (ROLE_STANDALONE)
[2006/06/12 11:12:42, 10] auth/auth.c:check_ntlm_password(259)
  check_ntlm_password: sam had nothing to say
[2006/06/12 11:12:42, 2] auth/auth.c:check_ntlm_password(312)
  check_ntlm_password:  Authentication for user [nobody] -> [nobody]
FAILED with error NT_STATUS_NO_SUCH_USER

-- 
-------+-++-+-+-+++++-+++++++++++-++++-+-+-+---+-+++++++--------
                     얍輕ㅅㅘㅇ  UENO Satoshi
                     satosi @ aqr.calmnet.ne.jp
                         from Tokyo,Japan
+++++-++++-++-++++-++---++-++-+-+-+-+-+------+-+-++-+-+-+-+++-+-



samba-jp メーリングリストの案内