Hillig Matthias
2004-Jan-29 13:24 UTC
[Samba] change_to_user: Invalid vuid - sporadically access denied
> Hi there, > > we're running into sporadic (!) problems when users access files read/write using rational clearcase. > The production is running on unix and automatically starts several telnet sessions from unix to win2k > (but only one at a time) that copy directory trees onto the samba share (sunos 5.8/samba 2.2.8a). > After a while in various parts of the production we ran sporadically into the following problems > ...>The error seems to be that a change_to_user operation does not work:> smbd/uid.c:change_to_user(138) > change_to_user: Invalid vuid used 100 > [2004/01/28 09:03:24, 3, effective(0, 0), real(0, 0)] > smbd/error.c:error_packet(113) > error packet at smbd/process.c(742) cmd=50 (SMBtrans2) > NT_STATUS_NETWORK_ACCESS_DENIED >Also the following messages seem to deal with that problem:> ulogoff, vuser id 101 does not map to user. > > This message is displayed sometimes with vuid 100 or 101 > > Sometimes the production does not get any errors... > > We where running up to debug level 9 to track the problem.... > Any ideas? Any help would be greatly appreciated....Thanks! > > > That is the error the user gets : > > Opening Telnet Connection to 139.21.158.110... > Starting view, mounting vob, determining drive > Using drive G: > set OFFICIAL_PROD=Y > Not checking whether other as VOBs are mounted > Creating 1st NFS safeguard file ... dir /OD /B . | %SystemRoot%\system32\find.exe /v ".cmake.state" >.\.tcm_remNT_nfs1_6881 > Access Denied > omake -f Makefile.nt -v AS_PLATFORM_ID=uw7 RepGenAPI.jar JCLEAN=Y -kW > omake: Error: Error opening CR cache file .cmake.state - File exists > No candidate in current view for "DummyStubClass" > ======== Rebuilding "DummyStubClass" =======> IF NOT EXIST classes\ReportService mkdir classes\ReportService > copy Makefile.rm classes\ReportService\_ReportTemplateManagerStub.class > Access Denied > 0 Files copied. > > > Samba Logfile:(loglevel 9) > > > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] > smbd/files.c:file_free(346) > freed files structure 12268 (3 used) > [2004/01/28 09:03:24, 2, effective(0, 0), real(0, 0)] > smbd/close.c:close_normal_file(229) > wa38039 closed file > vobs/as/src_as2.vbs/c/cdft/22/c/6800c6dfe44811d79bbd00105af6d150 (numopen=2) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] > smbd/files.c:file_free(346) > freed files structure 12171 (2 used) > [2004/01/28 09:03:24, 2, effective(0, 0), real(0, 0)] > smbd/close.c:close_normal_file(229) > wa38039 closed file > vobs/as/src_as_comp1.vbs/c/cdft/11/d/6fc1c01f53b211d7a891001083cf964f > (numopen=1) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] > smbd/files.c:file_free(346) > freed files structure 12170 (1 used) > [2004/01/28 09:03:24, 2, effective(0, 0), real(0, 0)] > smbd/close.c:close_normal_file(229) > wa38039 closed file view_area/prod06_uw7.vws/.s/00042/80000e2040167345tmp07916 > (numopen=0) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] > smbd/files.c:file_free(346) > freed files structure 12169 (0 used) > [2004/01/28 09:03:24, 3, effective(0, 0), real(0, 0)] > smbd/reply.c:reply_ulogoffX(1812) > ulogoffX vuid=100 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] 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 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(281) > smb_tid=0 > smb_pid=65279 > smb_uid=100 > smb_mid=13312 > smt_wct=2 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[0]=255 (0xFF) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[1]=0 (0x0) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(291) > smb_bcc=0 > [2004/01/28 09:03:24, 6, effective(0, 0), real(0, 0)] > lib/util_sock.c:write_socket(518)> > write_socket(12,43) > [2004/01/28 09:03:24, 6, effective(0, 0), real(0, 0)] > lib/util_sock.c:write_socket(521) > write_socket(12,43) wrote 43 > [2004/01/28 09:03:24, 6, effective(0, 0), real(0, 0)] > smbd/process.c:process_smb(845) > got message type 0x0 of len 0x48 > [2004/01/28 09:03:24, 3, effective(0, 0), real(0, 0)] > smbd/process.c:process_smb(846) > Transaction 2479 of length 76 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(275) > size=72 > smb_com=0x32 > smb_rcls=0 > smb_reh=0 > smb_err=0 > smb_flg=24 > smb_flg2=18439 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(281) > smb_tid=1 > smb_pid=1920 > smb_uid=100 > smb_mid=13377 > smt_wct=15 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[0]=4 (0x4) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[1]=0 (0x0) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[2]=2 (0x2) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[3]=64 (0x40) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[4]=0 (0x0) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[5]=0 (0x0) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[6]=0 (0x0) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[7]=0 (0x0) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[8]=0 (0x0) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[9]=4 (0x4) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[10]=68 (0x44) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[11]=0 (0x0) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[12]=0 (0x0) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[13]=1 (0x1) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[14]=7 (0x7) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(291) > smb_bcc=7 > [2004/01/28 09:03:24, 3, effective(0, 0), real(0, 0)] > smbd/process.c:switch_message(685) > switch message SMBtrans2 (pid 7243) > [2004/01/28 09:03:24, 2, effective(0, 0), real(0, 0)] > smbd/uid.c:change_to_user(138) > change_to_user: Invalid vuid used 100 > [2004/01/28 09:03:24, 3, effective(0, 0), real(0, 0)] > smbd/error.c:error_packet(113) > error packet at smbd/process.c(742) cmd=50 (SMBtrans2) > NT_STATUS_NETWORK_ACCESS_DENIED > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(275) > size=35 > smb_com=0x32 > smb_rcls=202 > smb_reh=0 > smb_err=49152 > smb_flg=136 > smb_flg2=16385 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(281) > smb_tid=1 > smb_pid=1920 > smb_uid=100 > smb_mid=13377 > smt_wct=0 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(291) > smb_bcc=0 > [2004/01/28 09:03:24, 6, effective(0, 0), real(0, 0)] > lib/util_sock.c:write_socket(518) > write_socket(12,39) > [2004/01/28 09:03:24, 6, effective(0, 0), real(0, 0)] > lib/util_sock.c:write_socket(521) > write_socket(12,39) wrote 39 > [2004/01/28 09:03:24, 6, effective(0, 0), real(0, 0)] > smbd/process.c:process_smb(845) > got message type 0x0 of len 0x27 > [2004/01/28 09:03:24, 3, effective(0, 0), real(0, 0)] > smbd/process.c:process_smb(846) > Transaction 2480 of length 43 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] 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 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(281) > smb_tid=0 > smb_pid=65279 > smb_uid=101 > > smb_mid=13441 > smt_wct=2 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[0]=255 (0xFF) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[1]=0 (0x0) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(291) > smb_bcc=0 > [2004/01/28 09:03:24, 3, effective(0, 0), real(0, 0)] > smbd/process.c:switch_message(685) > switch message SMBulogoffX (pid 7243) > [2004/01/28 09:03:24, 3, effective(0, 0), real(0, 0)] > smbd/sec_ctx.c:set_sec_ctx(329) > setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] > smbd/uid.c:change_to_root_user(217) > change_to_root_user: now uid=(0,0) gid=(0,0) > [2004/01/28 09:03:24, 3, effective(0, 0), real(0, 0)] > smbd/reply.c:reply_ulogoffX(1799) > ulogoff, vuser id 101 does not map to user. > [2004/01/28 09:03:24, 3, effective(0, 0), real(0, 0)] > smbd/reply.c:reply_ulogoffX(1812) > ulogoffX vuid=101 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] 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 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(281) > smb_tid=0 > smb_pid=65279 > smb_uid=101 > smb_mid=13441 > smt_wct=2 > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[0]=255 (0xFF) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(286) > smb_vwv[1]=0 (0x0) > [2004/01/28 09:03:24, 5, effective(0, 0), real(0, 0)] lib/util.c:show_msg(291) > smb_bcc=0 > [2004/01/28 09:03:24, 6, effective(0, 0), real(0, 0)] > lib/util_sock.c:write_socket(518) > write_socket(12,43) > [2004/01/28 09:03:24, 6, effective(0, 0), real(0, 0)] > lib/util_sock.c:write_socket(521) > > > Matthias Hillig > SBS ORS GD AHS OS 62 > Computer & Network Services (CNS) > *: <mailto:Matthias.Hillig@siemens.com> > *: +49 89 722 31614 > 6: +49 89 722 47847 > >