[OpenAFS] aklog sending domain with user name returning 32766 anonymous

David Broudy dave@broudy.net
Sat, 20 May 2006 17:56:59 -0600


Nothing in the fileserver logs, and I've included my afsd.log. I looked 
at this before sending to the list in the first place, but it doesn't 
mean much to me. I see the RXKADUNKNOWNKEY, but I don't see why.

Thanks,
Dave

time 960.688170, tid 4488 Ioctl return code 0x0
time 960.688180, tid 4488 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.688230, tid 4488 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.688280, tid 4988 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.688290, tid 4988 SMB received op 0x2e lsn 1
time 960.688290, tid 4988 Dispatch (2e)ReceiveV3ReadX vcp 0xa2e6e58 lana 
5 lsn 1
time 960.688290, tid 4988 smb_ReceiveV3Read fd 10740, off 0x11, size 0x1ff8
time 960.688300, tid 4988 cm_LockCheckRead scp 0x44d758 offset 17 length 
8184 code 0x0
time 960.688300, tid 4988 Ioctl uid 1 user af32898 name mriya\dave
time 960.688300, tid 4988 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.688360, tid 4988 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.688430, tid 3876 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.688440, tid 3876 SMB received op 0x4 lsn 1
time 960.688440, tid 3876 Dispatch (04)ReceiveCoreClose vcp 0xa2e6e58 
lana 5 lsn 1
time 960.688440, tid 3876 SMB ReceiveCoreClose fid 10740
time 960.688450, tid 3876 smb_CloseFID Closing fidp 0xaf53fc0 (fid=10740 
vcp=0xa2e6e58)
time 960.688460, tid 3876 smb_ReleaseVCNoLock vcp a2e6e58 ref 10
time 960.688460, tid 3876 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.688510, tid 3876 smb_ReleaseVC       vcp a2e6e58 ref 9
time 960.818730, tid 620 smb_HoldVCNoLock vcp a2e6e58 ref 9
time 960.818740, tid 620 SMB received op 0x32 lsn 1
time 960.818740, tid 620 Dispatch (32)ReceiveV3Tran2A vcp 0xa2e6e58 lana 
5 lsn 1
time 960.818750, tid 620 smb_HoldVC       vcp a2e6e58 ref 10
time 960.818750, tid 620 SMB3 received T2 init packet total data 0, cur 
data 0, max return data 40
time 960.818750, tid 620 AFS Server - Dispatch-2 
S(05)QueryFileInfo_ReceiveTran2QPathInfo vcp[a2e6e58] lana[5] lsn[1]
time 960.818760, tid 620 T2 QPathInfo type 0x101 path
time 960.818760, tid 620 smb_HoldVC       vcp a2e6e58 ref 11
time 960.818800, tid 620 CALL VL_GetEntryByName{UNO} name 
broudy.net:root.cell
time 960.818800, tid 620 cm_ConnByServer returning conn 0xaf331d0
time 960.818870, tid 620 cm_Analyze connp 0xaf331d0, code 0x1260b08
time 960.818870, tid 620 cm_Analyze: ignoring error code 0x1260b08 
(RXKADUNKNOWNKEY)
time 960.818870, tid 620 CALL VL_GetEntryByName{UNO} name 
broudy.net:root.cell FAILURE, code 0x1260b08
time 960.818880, tid 620 SMB SEND code 1260B08 as NT C0000322
time 960.818960, tid 620 smb_ReleaseVC       vcp a2e6e58 ref 11
time 960.818960, tid 620 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.818960, tid 620 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.818960, tid 620 smb_ReleaseVC       vcp a2e6e58 ref 9
time 960.829420, tid 5288 smb_HoldVCNoLock vcp a2e6e58 ref 9
time 960.829420, tid 5288 SMB received op 0xa2 lsn 1
time 960.829430, tid 5288 Dispatch (A2)ReceiveNTCreateX vcp 0xa2e6e58 
lana 5 lsn 1
time 960.829440, tid 5288 NTCreateX for [\_._AFS_IOCTL_._]
time 960.829440, tid 5288 ... da=[1] ea=[0] cd=[1] co=[140]
time 960.829440, tid 5288 ... share=[7] flags=[16] 
lastNamep=[\_._AFS_IOCTL_._]
time 960.829470, tid 5288 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.829480, tid 5288 NTCreateX Setting up IOCTL on fid[10741]
time 960.829480, tid 5288 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.829560, tid 5288 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.829690, tid 4876 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.829700, tid 4876 SMB received op 0x32 lsn 1
time 960.829700, tid 4876 Dispatch (32)ReceiveV3Tran2A vcp 0xa2e6e58 
lana 5 lsn 1
time 960.829700, tid 4876 smb_HoldVC       vcp a2e6e58 ref 11
time 960.829700, tid 4876 SMB3 received T2 init packet total data 0, cur 
data 0, max return data 24
time 960.829710, tid 4876 AFS Server - Dispatch-2 
S(07)SetInfoHandle_ReceiveTran2QFileInfo vcp[a2e6e58] lana[5] lsn[1]
time 960.829710, tid 4876 T2 QFileInfo type 0x102 fid 10741
time 960.829710, tid 4876 smb_HoldVC       vcp a2e6e58 ref 12
time 960.829780, tid 4876 smb_ReleaseVC       vcp a2e6e58 ref 12
time 960.829790, tid 4876 smb_ReleaseVC       vcp a2e6e58 ref 11
time 960.829790, tid 4876 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.829790, tid 4876 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.829850, tid 5296 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.829850, tid 5296 SMB received op 0x32 lsn 1
time 960.829860, tid 5296 Dispatch (32)ReceiveV3Tran2A vcp 0xa2e6e58 
lana 5 lsn 1
time 960.829860, tid 5296 smb_HoldVC       vcp a2e6e58 ref 11
time 960.829860, tid 5296 SMB3 received T2 init packet total data 0, cur 
data 0, max return data 24
time 960.829870, tid 5296 AFS Server - Dispatch-2 
S(07)SetInfoHandle_ReceiveTran2QFileInfo vcp[a2e6e58] lana[5] lsn[1]
time 960.829870, tid 5296 T2 QFileInfo type 0x102 fid 10741
time 960.829880, tid 5296 smb_HoldVC       vcp a2e6e58 ref 12
time 960.829940, tid 5296 smb_ReleaseVC       vcp a2e6e58 ref 12
time 960.829940, tid 5296 smb_ReleaseVC       vcp a2e6e58 ref 11
time 960.829950, tid 5296 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.829950, tid 5296 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.830020, tid 4964 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.830030, tid 4964 SMB received op 0x2e lsn 1
time 960.830030, tid 4964 Dispatch (2e)ReceiveV3ReadX vcp 0xa2e6e58 lana 
5 lsn 1
time 960.830030, tid 4964 smb_ReceiveV3Read fd 10741, off 0x0, size 0x3e8
time 960.830040, tid 4964 cm_LockCheckRead scp 0x44d758 offset 0 length 
1000 code 0x0
time 960.830040, tid 4964 Ioctl uid 1 user af32898 name mriya\dave
time 960.830060, tid 4964 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.830120, tid 4964 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.830190, tid 3980 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.830190, tid 3980 SMB received op 0x2e lsn 1
time 960.830190, tid 3980 Dispatch (2e)ReceiveV3ReadX vcp 0xa2e6e58 lana 
5 lsn 1
time 960.830200, tid 3980 smb_ReceiveV3Read fd 10741, off 0x0, size 0x1ff
time 960.830200, tid 3980 cm_LockCheckRead scp 0x44d758 offset 0 length 
511 code 0x0
time 960.830200, tid 3980 Ioctl uid 1 user af32898 name mriya\dave
time 960.830210, tid 3980 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.830280, tid 3980 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.830340, tid 4568 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.830340, tid 4568 SMB received op 0x2e lsn 1
time 960.830350, tid 4568 Dispatch (2e)ReceiveV3ReadX vcp 0xa2e6e58 lana 
5 lsn 1
time 960.830350, tid 4568 smb_ReceiveV3Read fd 10741, off 0x0, size 0x1ff
time 960.830350, tid 4568 cm_LockCheckRead scp 0x44d758 offset 0 length 
511 code 0x0
time 960.830360, tid 4568 Ioctl uid 1 user af32898 name mriya\dave
time 960.830360, tid 4568 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.830420, tid 4568 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.830490, tid 4884 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.830490, tid 4884 SMB received op 0x2e lsn 1
time 960.830500, tid 4884 Dispatch (2e)ReceiveV3ReadX vcp 0xa2e6e58 lana 
5 lsn 1
time 960.830500, tid 4884 smb_ReceiveV3Read fd 10741, off 0x0, size 0x40
time 960.830500, tid 4884 cm_LockCheckRead scp 0x44d758 offset 0 length 
64 code 0x0
time 960.830500, tid 4884 Ioctl uid 1 user af32898 name mriya\dave
time 960.830510, tid 4884 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.830580, tid 4884 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.830650, tid 1300 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.830650, tid 1300 SMB received op 0x2e lsn 1
time 960.830650, tid 1300 Dispatch (2e)ReceiveV3ReadX vcp 0xa2e6e58 lana 
5 lsn 1
time 960.830660, tid 1300 smb_ReceiveV3Read fd 10741, off 0x0, size 0x40
time 960.830660, tid 1300 cm_LockCheckRead scp 0x44d758 offset 0 length 
64 code 0x0
time 960.830660, tid 1300 Ioctl uid 1 user af32898 name mriya\dave
time 960.830670, tid 1300 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.830730, tid 1300 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.830800, tid 5516 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.830800, tid 5516 SMB received op 0x2e lsn 1
time 960.830800, tid 5516 Dispatch (2e)ReceiveV3ReadX vcp 0xa2e6e58 lana 
5 lsn 1
time 960.830810, tid 5516 smb_ReceiveV3Read fd 10741, off 0x0, size 0x4e
time 960.830810, tid 5516 cm_LockCheckRead scp 0x44d758 offset 0 length 
78 code 0x0
time 960.830810, tid 5516 Ioctl uid 1 user af32898 name mriya\dave
time 960.830820, tid 5516 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.830880, tid 5516 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.830940, tid 1008 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.830950, tid 1008 SMB received op 0x2e lsn 1
time 960.830950, tid 1008 Dispatch (2e)ReceiveV3ReadX vcp 0xa2e6e58 lana 
5 lsn 1
time 960.830960, tid 1008 smb_ReceiveV3Read fd 10741, off 0x0, size 0x1ff
time 960.830960, tid 1008 cm_LockCheckRead scp 0x44d758 offset 0 length 
511 code 0x0
time 960.830960, tid 1008 Ioctl uid 1 user af32898 name mriya\dave
time 960.830970, tid 1008 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.831030, tid 1008 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.831110, tid 5000 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.831110, tid 5000 SMB received op 0x2e lsn 1
time 960.831110, tid 5000 Dispatch (2e)ReceiveV3ReadX vcp 0xa2e6e58 lana 
5 lsn 1
time 960.831110, tid 5000 smb_ReceiveV3Read fd 10741, off 0x0, size 0x1ff
time 960.831120, tid 5000 cm_LockCheckRead scp 0x44d758 offset 0 length 
511 code 0x0
time 960.831120, tid 5000 Ioctl uid 1 user af32898 name mriya\dave
time 960.831130, tid 5000 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.831200, tid 5000 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.831300, tid 5684 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.831300, tid 5684 SMB received op 0x4 lsn 1
time 960.831300, tid 5684 Dispatch (04)ReceiveCoreClose vcp 0xa2e6e58 
lana 5 lsn 1
time 960.831310, tid 5684 SMB ReceiveCoreClose fid 10741
time 960.831310, tid 5684 smb_CloseFID Closing fidp 0xaf53fc0 (fid=10741 
vcp=0xa2e6e58)
time 960.831320, tid 5684 smb_ReleaseVCNoLock vcp a2e6e58 ref 10
time 960.831330, tid 5684 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.831390, tid 5684 smb_ReleaseVC       vcp a2e6e58 ref 9
time 960.831480, tid 5412 smb_HoldVCNoLock vcp a2e6e58 ref 9
time 960.831490, tid 5412 SMB received op 0xa2 lsn 1
time 960.831490, tid 5412 Dispatch (A2)ReceiveNTCreateX vcp 0xa2e6e58 
lana 5 lsn 1
time 960.831500, tid 5412 NTCreateX for [\_._AFS_IOCTL_._]
time 960.831500, tid 5412 ... da=[2019f] ea=[0] cd=[1] co=[42]
time 960.831500, tid 5412 ... share=[3] flags=[16] 
lastNamep=[\_._AFS_IOCTL_._]
time 960.831530, tid 5412 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.831530, tid 5412 NTCreateX Setting up IOCTL on fid[10742]
time 960.831540, tid 5412 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.831610, tid 5412 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.831690, tid 4084 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.831700, tid 4084 SMB received op 0xb lsn 1
time 960.831700, tid 4084 Dispatch (0b)ReceiveCoreWrite vcp 0xa2e6e58 
lana 5 lsn 1
time 960.831700, tid 4084 smb_ReceiveCoreWrite fid 10742, off 0x0, size 0x9
time 960.831720, tid 4084 Dispatch return  code 0x0 vcp 0xa2e6e58 lana 5 
lsn 1
time 960.831790, tid 4084 smb_ReleaseVC       vcp a2e6e58 ref 10
time 960.831870, tid 892 smb_HoldVCNoLock vcp a2e6e58 ref 10
time 960.831880, tid 892 SMB received op 0x2e lsn 1
time 960.831880, tid 892 Dispatch (2e)ReceiveV3ReadX vcp 0xa2e6e58 lana 
5 lsn 1
time 960.831880, tid 892 smb_ReceiveV3Read fd 10742, off 0x9, size 0x2000
time 960.831880, tid 892 cm_LockCheckRead scp 0x44d758 offset 9 length 
8192 code 0x0
time 960.831890, tid 892 Ioctl uid 1 user af32898 name mriya\dave
time 960.831890, tid 892 Ioctl opcode 0x1c


Jeffrey Altman wrote:
> Follow the debugging techniques section of the OAFW Release Notes and
> obtain the trace log output data and if possible bump the file server
> log level to 125 and examine the contents of that log.  Your answer will
> be in there somewhere.
>
> Jeffrey Altman
>
>
> Dave Broudy wrote:
>   
>> Indeed, my clients get access denied, even to \\afs\broudy.net, which is
>> system:anyuser rl, either via Start Run or via a mapped drive.
>>
>> I can access my afs space if I unlog, but obviously only the parts with
>> permissive acls.
>>
>> I've also found that I'm getting "ticket contained unknown key version
>> number" from pts and other commands, but on the client:
>>
>> c:\> kvno afs@BROUDY.NET
>> afs@BROUDY.NET: kvno = 3
>>
>> on all servers:
>>
>> # asetkey list
>> kvno 3: key is: (match on all servers)
>> All done.
>>     

-- 
Dave Broudy
dave@broudy.net
Phone: 303.278.0908      Mobile: 703.401.5955        Fax: 303.674.6840
http://www.broudy.net/
AIM/YIM: dbroudy