Trouble authenticating with OfficeJet CIFS client
Bob Richmond
bob at lorez.org
Wed Jul 18 15:34:53 GMT 2007
I have an HP OfficeJet 9130 network-attached multifunction printer, and
one of its functions is a scanner that includes an integrated CIFS
client for storing the resulting images.
I'm trying to get it to store the images on a Samba share, and every
time I attempt to authenticate from the printer console, it results in
an authentication error.
So I fired up wireshark and compared the smb transaction generated
through smbclient (which authenticates fine with client ntlmv2 auth =
yes turned on) and the transaction generated when attempting the same
authorization from the printer. The difference between smbclient and the
printer is that the printer, during NTLMSSP_NEGOTIATE, stores in its
"Security Blob," a GSS-API structure that contains the NTLMSSP section,
while smbclient contains a SPNEGO structure that contains the NTLMSSP
section:
Printer:
[2007/07/18 08:21:02, 10] lib/util.c:dump_data(2283)
[000] 4E 54 4C 4D 53 53 50 00 03 00 00 00 18 00 18 00 NTLMSSP. ........
[010] 40 00 00 00 82 00 82 00 58 00 00 00 08 00 08 00 @....... X.......
[020] DA 00 00 00 0E 00 0E 00 E2 00 00 00 00 00 00 00 ........ ........
[030] F0 00 00 00 10 00 10 00 F0 00 00 00 95 82 88 E0 ........ ........
[040] 8B 63 86 04 4B 8D F8 62 C8 64 2F AC 4D 0A 3E 14 .c..K..b .d/.M.>.
[050] DC 93 6A C2 C3 BB 8F 4C 98 6A B4 F6 E0 19 EC 78 ..j....L .j.....x
[060] 89 7E 8A 7E 4C 9D FA B2 01 01 00 00 00 00 00 00 .~.~L... ........
[070] 00 83 32 40 4F C9 C7 01 DC 93 6A C2 C3 BB 8F 4C ..2 at O... ..j....L
[080] 00 00 00 00 02 00 08 00 4D 00 45 00 47 00 41 00 ........ M.E.G.A.
[090] 01 00 08 00 4D 00 45 00 47 00 41 00 04 00 12 00 ....M.E. G.A.....
[0A0] 6C 00 6F 00 72 00 65 00 7A 00 2E 00 6F 00 72 00 l.o.r.e. z...o.r.
[0B0] 67 00 03 00 1C 00 6D 00 65 00 67 00 61 00 2E 00 g.....m. e.g.a...
[0C0] 6C 00 6F 00 72 00 65 00 7A 00 2E 00 6F 00 72 00 l.o.r.e. z...o.r.
[0D0] 67 00 00 00 00 00 00 00 00 00 4D 00 41 00 52 00 g....... ..M.A.R.
[0E0] 53 00 70 00 72 00 69 00 6E 00 74 00 65 00 72 00 S.p.r.i. n.t.e.r.
[0F0] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ........ ........
[100] 00 55 00 6E 00 69 00 78 00 20 00 76 00 65 00 72 .U.n.i.x . .v.e.r
[110] 00 73 00 69 00 6F 00 6E 00 20 00 32 00 31 00 30 .s.i.o.n . .2.1.0
[120] 00 30 00 00 00 63 00 69 00 66 00 73 00 00 00 00 .0...c.i .f.s....
[130] 00 .
smbclient:
[2007/07/15 23:36:18, 10] lib/util.c:dump_data(2283)
[000] A1 81 A7 30 81 A4 A2 81 A1 04 81 9E 4E 54 4C 4D ...0.... ....NTLM
[010] 53 53 50 00 03 00 00 00 18 00 18 00 40 00 00 00 SSP..... .... at ...
[020] 18 00 18 00 58 00 00 00 08 00 08 00 70 00 00 00 ....X... ....p...
[030] 0E 00 0E 00 78 00 00 00 08 00 08 00 86 00 00 00 ....x... ........
[040] 10 00 10 00 8E 00 00 00 15 02 08 60 BB 17 FF 58 ........ ...`...X
[050] 61 A8 73 BC 00 00 00 00 00 00 00 00 00 00 00 00 a.s..... ........
[060] 00 00 00 00 8C 28 FE 61 6F 5C F7 55 7D 66 4C DC .....(.a o\.U}fL.
[070] 27 E9 27 37 FE EB 86 FC B3 95 EE 2A 4D 00 41 00 '.'7.... ...*M.A.
[080] 52 00 53 00 70 00 72 00 69 00 6E 00 74 00 65 00 R.S.p.r. i.n.t.e.
[090] 72 00 45 00 56 00 49 00 4C 00 6D 43 A6 D9 DD 75 r.E.V.I. L.mC...u
[0A0] A9 3A 15 7C 30 8C 06 18 9B B9 00 55 00 6E 00 69 .:.|0... ...U.n.i
[0B0] 00 78 00 00 00 53 00 61 00 6D 00 62 00 61 00 00 .x...S.a .m.b.a..
[0C0] 00 .
As a shot in the dark, I tried use spnego = no on the Samba server, and
the printer was able to authenticate.
The debug 10 log from the printer follows:
[2007/07/18 08:21:02, 6] param/loadparm.c:lp_file_list_changed(3073)
lp_file_list_changed()
file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Wed
Jul 18 08:19:09 2007
[2007/07/18 08:21:02, 3] smbd/oplock.c:init_oplocks(863)
init_oplocks: initializing messages.
[2007/07/18 08:21:02, 3] smbd/oplock_linux.c:linux_init_kernel_oplocks(276)
Linux kernel oplocks enabled
[2007/07/18 08:21:02, 10]
lib/util_sock.c:read_smb_length_return_keepalive(623)
got smb length of 58
[2007/07/18 08:21:02, 6] smbd/process.c:process_smb(1067)
got message type 0x0 of len 0x3a
[2007/07/18 08:21:02, 3] smbd/process.c:process_smb(1068)
Transaction 0 of length 62
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(506)
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(516)
size=58
smb_com=0x72
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=51203
smb_tid=0
smb_pid=2132
smb_uid=0
smb_mid=0
smt_wct=0
smb_bcc=23
[2007/07/18 08:21:02, 10] lib/util.c:dump_data(2283)
[000] 02 4C 41 4E 4D 41 4E 32 2E 31 00 02 4E 54 20 4C .LANMAN2 .1..NT L
[010] 4D 20 30 2E 31 32 00 M 0.12.
[2007/07/18 08:21:02, 3] smbd/process.c:switch_message(926)
switch message SMBnegprot (pid 13957) conn 0x0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 5] smbd/uid.c:change_to_root_user(288)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2007/07/18 08:21:02, 3] smbd/negprot.c:reply_negprot(505)
Requested protocol [LANMAN2.1]
[2007/07/18 08:21:02, 3] smbd/negprot.c:reply_negprot(505)
Requested protocol [NT LM 0.12]
[2007/07/18 08:21:02, 10] lib/util.c:set_remote_arch(2258)
set_remote_arch: Client arch is 'Win2K'
[2007/07/18 08:21:02, 6] param/loadparm.c:lp_file_list_changed(3073)
lp_file_list_changed()
file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Wed
Jul 18 08:19:09 2007
[2007/07/18 08:21:02, 5] smbd/connection.c:claim_connection(182)
claiming 0
[2007/07/18 08:21:02, 6] param/loadparm.c:lp_file_list_changed(3073)
lp_file_list_changed()
file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Wed
Jul 18 08:19:09 2007
[2007/07/18 08:21:02, 10] lib/util.c:set_remote_arch(2258)
set_remote_arch: Client arch is 'Vista'
[2007/07/18 08:21:02, 3] smbd/negprot.c:reply_nt1(364)
using SPNEGO
[2007/07/18 08:21:02, 3] smbd/negprot.c:reply_negprot(606)
Selected protocol NT LM 0.12
[2007/07/18 08:21:02, 5] smbd/negprot.c:reply_negprot(612)
negprot index=1
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(506)
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(516)
size=127
smb_com=0x72
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=136
smb_flg2=51201
smb_tid=0
smb_pid=2132
smb_uid=0
smb_mid=0
smt_wct=17
smb_vwv[ 0]= 1 (0x1)
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]=34048 (0x8500)
smb_vwv[ 8]= 54 (0x36)
smb_vwv[ 9]=64768 (0xFD00)
smb_vwv[10]=33011 (0x80F3)
smb_vwv[11]= 128 (0x80)
smb_vwv[12]=12931 (0x3283)
smb_vwv[13]=20288 (0x4F40)
smb_vwv[14]=51145 (0xC7C9)
smb_vwv[15]=41985 (0xA401)
smb_vwv[16]= 1 (0x1)
smb_bcc=58
[2007/07/18 08:21:02, 10] lib/util.c:dump_data(2283)
[000] 6D 65 67 61 00 00 00 00 00 00 00 00 00 00 00 00 mega.... ........
[010] 60 28 06 06 2B 06 01 05 05 02 A0 1E 30 1C A0 0E `(..+... ....0...
[020] 30 0C 06 0A 2B 06 01 04 01 82 37 02 02 0A A3 0A 0...+... ..7.....
[030] 30 08 A0 06 1B 04 4E 4F 4E 45 0.....NO NE
[2007/07/18 08:21:02, 10]
lib/util_sock.c:read_smb_length_return_keepalive(623)
got smb length of 144
[2007/07/18 08:21:02, 6] smbd/process.c:process_smb(1067)
got message type 0x0 of len 0x90
[2007/07/18 08:21:02, 3] smbd/process.c:process_smb(1068)
Transaction 1 of length 148
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(506)
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(516)
size=144
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=51201
smb_tid=0
smb_pid=2132
smb_uid=0
smb_mid=1
smt_wct=12
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 164 (0xA4)
smb_vwv[ 2]= 5120 (0x1400)
smb_vwv[ 3]= 50 (0x32)
smb_vwv[ 4]= 2132 (0x854)
smb_vwv[ 5]= 0 (0x0)
smb_vwv[ 6]= 0 (0x0)
smb_vwv[ 7]= 36 (0x24)
smb_vwv[ 8]= 0 (0x0)
smb_vwv[ 9]= 0 (0x0)
smb_vwv[10]= 84 (0x54)
smb_vwv[11]=32768 (0x8000)
smb_bcc=85
[2007/07/18 08:21:02, 10] lib/util.c:dump_data(2283)
[000] 4E 54 4C 4D 53 53 50 00 01 00 00 00 97 92 08 E0 NTLMSSP. ........
[010] 04 00 04 00 20 00 00 00 00 00 00 00 00 00 00 00 .... ... ........
[020] 4D 41 52 53 00 55 00 6E 00 69 00 78 00 20 00 76 MARS.U.n .i.x. .v
[030] 00 65 00 72 00 73 00 69 00 6F 00 6E 00 20 00 32 .e.r.s.i .o.n. .2
[040] 00 31 00 30 00 30 00 00 00 63 00 69 00 66 00 73 .1.0.0.. .c.i.f.s
[050] 00 00 00 00 00 .....
[2007/07/18 08:21:02, 3] smbd/process.c:switch_message(926)
switch message SMBsesssetupX (pid 13957) conn 0x0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 5] smbd/uid.c:change_to_root_user(288)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2007/07/18 08:21:02, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244)
wct=12 flg2=0xc801
[2007/07/18 08:21:02, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1029)
Doing spnego session setup
[2007/07/18 08:21:02, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1060)
NativeOS=[Unix version 2100] NativeLanMan=[cifs] PrimaryDomain=[]
[2007/07/18 08:21:02, 10] smbd/password.c:register_vuid(186)
register_vuid: allocated vuid = 100
[2007/07/18 08:21:02, 5] auth/auth.c:make_auth_context_subsystem(486)
Making default auth method list for standalone security=user, encrypt
passwords = yes
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(47)
Attempting to register auth backend sam
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(59)
Successfully added auth method 'sam'
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(47)
Attempting to register auth backend sam_ignoredomain
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(59)
Successfully added auth method 'sam_ignoredomain'
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(47)
Attempting to register auth backend unix
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(59)
Successfully added auth method 'unix'
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(47)
Attempting to register auth backend winbind
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(59)
Successfully added auth method 'smbserver'
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(47)
Attempting to register auth backend trustdomain
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(59)
Successfully added auth method 'trustdomain'
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(47)
Attempting to register auth backend ntdomain
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(59)
Successfully added auth method 'ntdomain'
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(47)
Attempting to register auth backend guest
[2007/07/18 08:21:02, 5] auth/auth.c:smb_register_auth(59)
Successfully added auth method 'guest'
[2007/07/18 08:21:02, 5] auth/auth.c:load_auth_module(391)
load_auth_module: Attempting to find an auth method to match guest
[2007/07/18 08:21:02, 5] auth/auth.c:load_auth_module(416)
load_auth_module: auth method guest has a valid init
[2007/07/18 08:21:02, 5] auth/auth.c:load_auth_module(391)
load_auth_module: Attempting to find an auth method to match sam
[2007/07/18 08:21:02, 5] auth/auth.c:load_auth_module(416)
load_auth_module: auth method sam has a valid init
[2007/07/18 08:21:02, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(63)
Got NTLMSSP neg_flags=0xe0089297
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_ALWAYS_SIGN
NTLMSSP_NEGOTIATE_NTLM2
NTLMSSP_NEGOTIATE_128
NTLMSSP_NEGOTIATE_KEY_EXCH
NTLMSSP_NEGOTIATE_56
[2007/07/18 08:21:02, 5] auth/auth.c:get_ntlm_challenge(97)
auth_get_challenge: module guest did not want to specify a challenge
[2007/07/18 08:21:02, 5] auth/auth.c:get_ntlm_challenge(97)
auth_get_challenge: module sam did not want to specify a challenge
[2007/07/18 08:21:02, 5] auth/auth.c:get_ntlm_challenge(137)
auth_context challenge created by random
[2007/07/18 08:21:02, 5] auth/auth.c:get_ntlm_challenge(138)
challenge is:
[2007/07/18 08:21:02, 5] lib/util.c:dump_data(2283)
[000] 71 EE 53 BB 40 A4 84 DC q.S. at ...
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(506)
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(516)
size=242
smb_com=0x73
smb_rcls=22
smb_reh=0
smb_err=49152
smb_flg=136
smb_flg2=51201
smb_tid=0
smb_pid=2132
smb_uid=100
smb_mid=1
smt_wct=4
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
smb_vwv[ 2]= 0 (0x0)
smb_vwv[ 3]= 138 (0x8A)
smb_bcc=199
[2007/07/18 08:21:02, 10] lib/util.c:dump_data(2283)
[000] 4E 54 4C 4D 53 53 50 00 02 00 00 00 08 00 08 00 NTLMSSP. ........
[010] 30 00 00 00 95 82 8A E0 71 EE 53 BB 40 A4 84 DC 0....... q.S. at ...
[020] 00 00 00 00 00 00 00 00 52 00 52 00 38 00 00 00 ........ R.R.8...
[030] 4D 00 45 00 47 00 41 00 02 00 08 00 4D 00 45 00 M.E.G.A. ....M.E.
[040] 47 00 41 00 01 00 08 00 4D 00 45 00 47 00 41 00 G.A..... M.E.G.A.
[050] 04 00 12 00 6C 00 6F 00 72 00 65 00 7A 00 2E 00 ....l.o. r.e.z...
[060] 6F 00 72 00 67 00 03 00 1C 00 6D 00 65 00 67 00 o.r.g... ..m.e.g.
[070] 61 00 2E 00 6C 00 6F 00 72 00 65 00 7A 00 2E 00 a...l.o. r.e.z...
[080] 6F 00 72 00 67 00 00 00 00 00 00 55 00 6E 00 69 o.r.g... ...U.n.i
[090] 00 78 00 00 00 53 00 61 00 6D 00 62 00 61 00 20 .x...S.a .m.b.a.
[0A0] 00 33 00 2E 00 30 00 2E 00 32 00 35 00 62 00 2D .3...0.. .2.5.b.-
[0B0] 00 32 00 2E 00 66 00 63 00 37 00 00 00 4D 00 41 .2...f.c .7...M.A
[0C0] 00 52 00 53 00 00 00 .R.S...
[2007/07/18 08:21:02, 10]
lib/util_sock.c:read_smb_length_return_keepalive(623)
got smb length of 364
[2007/07/18 08:21:02, 6] smbd/process.c:process_smb(1067)
got message type 0x0 of len 0x16c
[2007/07/18 08:21:02, 3] smbd/process.c:process_smb(1068)
Transaction 2 of length 368
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(506)
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(516)
size=364
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=51201
smb_tid=0
smb_pid=2132
smb_uid=100
smb_mid=2
smt_wct=12
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 304 (0x130)
smb_vwv[ 2]= 5120 (0x1400)
smb_vwv[ 3]= 50 (0x32)
smb_vwv[ 4]= 2132 (0x854)
smb_vwv[ 5]= 0 (0x0)
smb_vwv[ 6]= 0 (0x0)
smb_vwv[ 7]= 256 (0x100)
smb_vwv[ 8]= 0 (0x0)
smb_vwv[ 9]= 0 (0x0)
smb_vwv[10]= 84 (0x54)
smb_vwv[11]=32768 (0x8000)
smb_bcc=305
[2007/07/18 08:21:02, 10] lib/util.c:dump_data(2283)
[000] 4E 54 4C 4D 53 53 50 00 03 00 00 00 18 00 18 00 NTLMSSP. ........
[010] 40 00 00 00 82 00 82 00 58 00 00 00 08 00 08 00 @....... X.......
[020] DA 00 00 00 0E 00 0E 00 E2 00 00 00 00 00 00 00 ........ ........
[030] F0 00 00 00 10 00 10 00 F0 00 00 00 95 82 88 E0 ........ ........
[040] 8B 63 86 04 4B 8D F8 62 C8 64 2F AC 4D 0A 3E 14 .c..K..b .d/.M.>.
[050] DC 93 6A C2 C3 BB 8F 4C 98 6A B4 F6 E0 19 EC 78 ..j....L .j.....x
[060] 89 7E 8A 7E 4C 9D FA B2 01 01 00 00 00 00 00 00 .~.~L... ........
[070] 00 83 32 40 4F C9 C7 01 DC 93 6A C2 C3 BB 8F 4C ..2 at O... ..j....L
[080] 00 00 00 00 02 00 08 00 4D 00 45 00 47 00 41 00 ........ M.E.G.A.
[090] 01 00 08 00 4D 00 45 00 47 00 41 00 04 00 12 00 ....M.E. G.A.....
[0A0] 6C 00 6F 00 72 00 65 00 7A 00 2E 00 6F 00 72 00 l.o.r.e. z...o.r.
[0B0] 67 00 03 00 1C 00 6D 00 65 00 67 00 61 00 2E 00 g.....m. e.g.a...
[0C0] 6C 00 6F 00 72 00 65 00 7A 00 2E 00 6F 00 72 00 l.o.r.e. z...o.r.
[0D0] 67 00 00 00 00 00 00 00 00 00 4D 00 41 00 52 00 g....... ..M.A.R.
[0E0] 53 00 70 00 72 00 69 00 6E 00 74 00 65 00 72 00 S.p.r.i. n.t.e.r.
[0F0] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ........ ........
[100] 00 55 00 6E 00 69 00 78 00 20 00 76 00 65 00 72 .U.n.i.x . .v.e.r
[110] 00 73 00 69 00 6F 00 6E 00 20 00 32 00 31 00 30 .s.i.o.n . .2.1.0
[120] 00 30 00 00 00 63 00 69 00 66 00 73 00 00 00 00 .0...c.i .f.s....
[130] 00 .
[2007/07/18 08:21:02, 3] smbd/process.c:switch_message(926)
switch message SMBsesssetupX (pid 13957) conn 0x0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 5] smbd/uid.c:change_to_root_user(288)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2007/07/18 08:21:02, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244)
wct=12 flg2=0xc801
[2007/07/18 08:21:02, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1029)
Doing spnego session setup
[2007/07/18 08:21:02, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1060)
NativeOS=[Unix version 2100] NativeLanMan=[cifs] PrimaryDomain=[]
[2007/07/18 08:21:02, 3] libsmb/ntlmssp.c:ntlmssp_server_auth(739)
Got user=[printer] domain=[MARS] workstation=[] len1=24 len2=130
[2007/07/18 08:21:02, 6] param/loadparm.c:lp_file_list_changed(3073)
lp_file_list_changed()
file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Wed
Jul 18 08:19:09 2007
[2007/07/18 08:21:02, 4] smbd/map_username.c:map_username(111)
Scanning username map /etc/samba/smbusers
[2007/07/18 08:21:02, 10] smbd/password.c:user_in_list(487)
user_in_list: checking user printer in list
[2007/07/18 08:21:02, 10] smbd/password.c:user_in_list(492)
user_in_list: checking user |printer| against |administrator|
[2007/07/18 08:21:02, 10] smbd/password.c:user_in_list(492)
user_in_list: checking user |printer| against |admin|
[2007/07/18 08:21:02, 10] smbd/password.c:user_in_list(487)
user_in_list: checking user printer in list
[2007/07/18 08:21:02, 10] smbd/password.c:user_in_list(492)
user_in_list: checking user |printer| against |guest|
[2007/07/18 08:21:02, 10] smbd/password.c:user_in_list(492)
user_in_list: checking user |printer| against |pcguest|
[2007/07/18 08:21:02, 10] smbd/password.c:user_in_list(492)
user_in_list: checking user |printer| against |smbguest|
[2007/07/18 08:21:02, 5] auth/auth_util.c:make_user_info_map(161)
make_user_info_map: Mapping user [MARS]\[printer] from workstation []
[2007/07/18 08:21:02, 5] auth/auth_util.c:make_user_info(75)
attempting to make a user_info for printer (printer)
[2007/07/18 08:21:02, 5] auth/auth_util.c:make_user_info(85)
making strings for printer's user_info struct
[2007/07/18 08:21:02, 5] auth/auth_util.c:make_user_info(117)
making blobs for printer's user_info struct
[2007/07/18 08:21:02, 10] auth/auth_util.c:make_user_info(135)
made an encrypted user_info for printer (printer)
[2007/07/18 08:21:02, 3] auth/auth.c:check_ntlm_password(221)
check_ntlm_password: Checking password for unmapped user
[MARS]\[printer]@[] with the new password interface
[2007/07/18 08:21:02, 3] auth/auth.c:check_ntlm_password(224)
check_ntlm_password: mapped user is: [MEGA]\[printer]@[]
[2007/07/18 08:21:02, 10] auth/auth.c:check_ntlm_password(233)
check_ntlm_password: auth_context challenge created by random
[2007/07/18 08:21:02, 10] auth/auth.c:check_ntlm_password(235)
challenge is:
[2007/07/18 08:21:02, 5] lib/util.c:dump_data(2283)
[000] 71 EE 53 BB 40 A4 84 DC q.S. at ...
[2007/07/18 08:21:02, 10] auth/auth.c:check_ntlm_password(261)
check_ntlm_password: guest had nothing to say
[2007/07/18 08:21:02, 8] lib/util.c:is_myname(2095)
is_myname("MEGA") returns 1
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 10] passdb/pdb_smbpasswd.c:smbpasswd_getsampwnam(1363)
getsampwnam (smbpasswd): search by name: printer
[2007/07/18 08:21:02, 10] passdb/pdb_smbpasswd.c:startsmbfilepwent(230)
startsmbfilepwent_internal: opening file /etc/samba/smbpasswd
[2007/07/18 08:21:02, 5] passdb/pdb_smbpasswd.c:getsmbfilepwent(534)
getsmbfilepwent: returning passwd entry for user sonos, uid 500
[2007/07/18 08:21:02, 5] passdb/pdb_smbpasswd.c:getsmbfilepwent(534)
getsmbfilepwent: returning passwd entry for user printer, uid 501
[2007/07/18 08:21:02, 7] passdb/pdb_smbpasswd.c:endsmbfilepwent(347)
endsmbfilepwent_internal: closed password file.
[2007/07/18 08:21:02, 10] passdb/pdb_smbpasswd.c:smbpasswd_getsampwnam(1385)
getsampwnam (smbpasswd): found by name: printer
[2007/07/18 08:21:02, 5] lib/username.c:Get_Pwnam_alloc(131)
Finding user printer
[2007/07/18 08:21:02, 5] lib/username.c:Get_Pwnam_internals(75)
Trying _Get_Pwnam(), username as lowercase is printer
[2007/07/18 08:21:02, 5] lib/username.c:Get_Pwnam_internals(108)
Get_Pwnam_internals did find user [printer]!
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_username(581)
pdb_set_username: setting username printer, was
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_fullname(650)
pdb_set_full_name: setting full name , was
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_domain(604)
pdb_set_domain: setting domain MEGA, was
[2007/07/18 08:21:02, 4] lib/substitute.c:automount_server(407)
Home server: mega
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_profile_path(696)
pdb_set_profile_path: setting profile path \\mega\printer\profile, was
[2007/07/18 08:21:02, 4] lib/substitute.c:automount_server(407)
Home server: mega
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_homedir(743)
pdb_set_homedir: setting home dir \\mega\printer, was
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(719)
pdb_set_dir_drive: setting dir drive , was NULL
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_logon_script(673)
pdb_set_logon_script: setting logon script , was
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_user_sid(510)
pdb_set_user_sid: setting user sid
S-1-5-21-3555924757-3702865982-2714505642-2002
[2007/07/18 08:21:02, 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-3555924757-3702865982-2714505642-2002
from rid 2002
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 10] lib/account_pol.c:account_policy_get(294)
account_policy_get: name: maximum password age, val: -1
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 10] lib/account_pol.c:account_policy_get(294)
account_policy_get: name: password history, val: 0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_username(581)
pdb_set_username: setting username printer, was
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_domain(604)
pdb_set_domain: setting domain MEGA, was
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_nt_username(627)
pdb_set_nt_username: setting nt username , was
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_fullname(650)
pdb_set_full_name: setting full name , was
[2007/07/18 08:21:02, 4] lib/substitute.c:automount_server(407)
Home server: mega
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_homedir(743)
pdb_set_homedir: setting home dir \\mega\printer, was
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(719)
pdb_set_dir_drive: setting dir drive , was NULL
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_logon_script(673)
pdb_set_logon_script: setting logon script , was
[2007/07/18 08:21:02, 4] lib/substitute.c:automount_server(407)
Home server: mega
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_profile_path(696)
pdb_set_profile_path: setting profile path \\mega\printer\profile, was
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_workstations(786)
pdb_set_workstations: setting workstations , was
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 10] lib/account_pol.c:account_policy_get(294)
account_policy_get: name: password history, val: 0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 10] passdb/pdb_get_set.c:pdb_set_user_sid(510)
pdb_set_user_sid: setting user sid
S-1-5-21-3555924757-3702865982-2714505642-2002
[2007/07/18 08:21:02, 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-3555924757-3702865982-2714505642-2002
from rid 2002
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 9] passdb/passdb.c:pdb_update_autolock_flag(1418)
pdb_update_autolock_flag: Account printer not autolocked, no check needed
[2007/07/18 08:21:02, 4] libsmb/ntlm_check.c:ntlm_password_check(288)
ntlm_password_check: Checking NTLMv2 password with domain [MARS]
[2007/07/18 08:21:02, 4] auth/auth_sam.c:sam_account_ok(138)
sam_account_ok: Checking SMB password for user printer
[2007/07/18 08:21:02, 5] auth/auth_sam.c:logon_hours_ok(120)
logon_hours_ok: user printer allowed to logon at this time (Wed Jul
18 15:21:02 2007
)
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 10] lib/account_pol.c:account_policy_get(294)
account_policy_get: name: maximum password age, val: -1
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 10] lib/util_pw.c:getpwnam_alloc(76)
Got printer from pwnam_cache
[2007/07/18 08:21:02, 10] lib/util_pw.c:getpwnam_alloc(76)
Got printer from pwnam_cache
[2007/07/18 08:21:02, 10] lib/system_smbd.c:sys_getgrouplist(125)
sys_getgrouplist: user [printer]
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 10] passdb/lookup_sid.c:legacy_gid_to_sid(1197)
LEGACY: gid 501 -> sid S-1-22-2-501
[2007/07/18 08:21:02, 5] auth/auth_util.c:make_server_info_sam(623)
make_server_info_sam: made server info for user printer -> printer
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 3] auth/auth.c:check_ntlm_password(270)
check_ntlm_password: sam authentication for user [printer] succeeded
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 5] auth/auth.c:check_ntlm_password(296)
check_ntlm_password: PAM Account for user [printer] succeeded
[2007/07/18 08:21:02, 2] auth/auth.c:check_ntlm_password(309)
check_ntlm_password: authentication for user [printer] -> [printer]
-> [printer] succeeded
[2007/07/18 08:21:02, 5] auth/auth_util.c:free_user_info(2045)
attempting to free (and zero) a user_info structure
[2007/07/18 08:21:02, 10] auth/auth_util.c:free_user_info(2049)
structure was created for printer
[2007/07/18 08:21:02, 10] auth/auth_util.c:create_local_nt_token(844)
Create local NT token for S-1-5-21-3555924757-3702865982-2714505642-2002
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1269)
LEGACY: mapping failed for sid S-1-5-32-544
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1269)
LEGACY: mapping failed for sid S-1-5-32-545
[2007/07/18 08:21:02, 3] lib/privileges.c:get_privileges(261)
get_privileges: No privileges assigned to SID
[S-1-5-21-3555924757-3702865982-2714505642-2002]
[2007/07/18 08:21:02, 3] lib/privileges.c:get_privileges(261)
get_privileges: No privileges assigned to SID [S-1-22-2-501]
[2007/07/18 08:21:02, 5] lib/privileges.c:get_privileges_for_sids(460)
get_privileges_for_sids: sid = S-1-1-0
Privilege set:
SE_PRIV 0x0 0x0 0x0 0x0
[2007/07/18 08:21:02, 3] lib/privileges.c:get_privileges(261)
get_privileges: No privileges assigned to SID [S-1-5-2]
[2007/07/18 08:21:02, 3] lib/privileges.c:get_privileges(261)
get_privileges: No privileges assigned to SID [S-1-5-11]
[2007/07/18 08:21:02, 10] passdb/lookup_sid.c:sid_to_gid(1437)
sid S-1-22-2-501 -> gid 501
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1269)
LEGACY: mapping failed for sid S-1-1-0
[2007/07/18 08:21:02, 10] auth/auth_util.c:create_local_token(1039)
Could not convert SID S-1-1-0 to gid, ignoring it
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1269)
LEGACY: mapping failed for sid S-1-5-2
[2007/07/18 08:21:02, 10] auth/auth_util.c:create_local_token(1039)
Could not convert SID S-1-5-2 to gid, ignoring it
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:push_sec_ctx(208)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 3] smbd/uid.c:push_conn_ctx(358)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1269)
LEGACY: mapping failed for sid S-1-5-11
[2007/07/18 08:21:02, 10] auth/auth_util.c:create_local_token(1039)
Could not convert SID S-1-5-11 to gid, ignoring it
[2007/07/18 08:21:02, 10] auth/auth_util.c:debug_nt_user_token(454)
NT user token of user S-1-5-21-3555924757-3702865982-2714505642-2002
contains 5 SIDs
SID[ 0]: S-1-5-21-3555924757-3702865982-2714505642-2002
SID[ 1]: S-1-22-2-501
SID[ 2]: S-1-1-0
SID[ 3]: S-1-5-2
SID[ 4]: S-1-5-11
SE_PRIV 0x0 0x0 0x0 0x0
[2007/07/18 08:21:02, 10]
auth/auth_ntlmssp.c:auth_ntlmssp_check_password(137)
Got NT session key of length 16
[2007/07/18 08:21:02, 10] libsmb/ntlmssp.c:ntlmssp_server_auth(836)
ntlmssp_server_auth: Failed to create NTLM session key.
[2007/07/18 08:21:02, 5] libsmb/ntlmssp.c:ntlmssp_server_auth(862)
server session key is invalid (len == 0), cannot do KEY_EXCH!
[2007/07/18 08:21:02, 10] smbd/password.c:register_vuid(186)
register_vuid: allocated vuid = 101
[2007/07/18 08:21:02, 10] lib/util_pw.c:getpwnam_alloc(76)
Got printer from pwnam_cache
[2007/07/18 08:21:02, 10] smbd/password.c:register_vuid(277)
register_vuid: (501,501) printer printer MEGA guest=0
[2007/07/18 08:21:02, 3] smbd/password.c:register_vuid(280)
User name: printer Real name:
[2007/07/18 08:21:02, 3] smbd/password.c:register_vuid(301)
UNIX uid 501 is UNIX user printer, and will be vuid 101
[2007/07/18 08:21:02, 3] smbd/password.c:register_vuid(341)
Using static (or previously created) service for user 'printer'; path
= '/srv/printer'
[2007/07/18 08:21:02, 6] param/loadparm.c:lp_file_list_changed(3073)
lp_file_list_changed()
file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Wed
Jul 18 08:19:09 2007
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(506)
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(516)
size=104
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=136
smb_flg2=51201
smb_tid=0
smb_pid=2132
smb_uid=101
smb_mid=2
smt_wct=4
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
smb_vwv[ 2]= 0 (0x0)
smb_vwv[ 3]= 0 (0x0)
smb_bcc=61
[2007/07/18 08:21:02, 10] lib/util.c:dump_data(2283)
[000] 00 55 00 6E 00 69 00 78 00 00 00 53 00 61 00 6D .U.n.i.x ...S.a.m
[010] 00 62 00 61 00 20 00 33 00 2E 00 30 00 2E 00 32 .b.a. .3 ...0...2
[020] 00 35 00 62 00 2D 00 32 00 2E 00 66 00 63 00 37 .5.b.-.2 ...f.c.7
[030] 00 00 00 4D 00 41 00 52 00 53 00 00 00 ...M.A.R .S...
[2007/07/18 08:21:02, 10]
lib/util_sock.c:read_smb_length_return_keepalive(623)
got smb length of 66
[2007/07/18 08:21:02, 6] smbd/process.c:process_smb(1067)
got message type 0x0 of len 0x42
[2007/07/18 08:21:02, 3] smbd/process.c:process_smb(1068)
Transaction 3 of length 70
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(506)
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(516)
size=66
smb_com=0x75
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=51201
smb_tid=0
smb_pid=2132
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]= 1 (0x1)
smb_bcc=23
[2007/07/18 08:21:02, 10] lib/util.c:dump_data(2283)
[000] 00 70 00 72 00 69 00 6E 00 74 00 65 00 72 00 00 .p.r.i.n .t.e.r..
[010] 00 3F 3F 3F 3F 3F 00 .?????.
[2007/07/18 08:21:02, 3] smbd/process.c:switch_message(926)
switch message SMBtconX (pid 13957) conn 0x0
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 5] smbd/uid.c:change_to_root_user(288)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2007/07/18 08:21:02, 4] smbd/reply.c:reply_tcon_and_X(506)
Client requested device type [?????] for share [printer]
[2007/07/18 08:21:02, 1] smbd/service.c:make_connection(1115)
make_connection: refusing to connect with no session setup
[2007/07/18 08:21:02, 3] smbd/error.c:error_packet_set(106)
error packet at smbd/reply.c(514) cmd=117 (SMBtconX)
NT_STATUS_ACCESS_DENIED
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(506)
[2007/07/18 08:21:02, 5] lib/util.c:show_msg(516)
size=35
smb_com=0x75
smb_rcls=34
smb_reh=0
smb_err=49152
smb_flg=136
smb_flg2=51201
smb_tid=0
smb_pid=2132
smb_uid=100
smb_mid=3
smt_wct=0
smb_bcc=0
[2007/07/18 08:21:02, 10] lib/util_sock.c:read_data(525)
read_data: read of 4 returned 0. Error = Success
[2007/07/18 08:21:02, 10] lib/util_sock.c:receive_smb_raw(672)
receive_smb_raw: length < 0!
[2007/07/18 08:21:02, 3] smbd/process.c:timeout_processing(1328)
timeout_processing: End of file from client (client has disconnected).
[2007/07/18 08:21:02, 5] lib/gencache.c:gencache_shutdown(94)
Closing cache file
[2007/07/18 08:21:02, 5] libsmb/namecache.c:namecache_shutdown(79)
namecache_shutdown: netbios namecache closed successfully.
[2007/07/18 08:21:02, 3] smbd/sec_ctx.c:set_sec_ctx(241)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_nt_user_token(448)
NT user token: (NULL)
[2007/07/18 08:21:02, 5] auth/auth_util.c:debug_unix_user_token(474)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2007/07/18 08:21:02, 5] smbd/uid.c:change_to_root_user(288)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2007/07/18 08:21:02, 3] smbd/connection.c:yield_connection(69)
Yielding connection to
[2007/07/18 08:21:02, 3] smbd/server.c:exit_server_common(768)
Server exit (normal exit)
More information about the samba-technical
mailing list