[OpenAFS-devel] Odd Behavior with 1.4.2fc3

[Jason McCormick]

This is a multi-part message in MIME format.
--------------040407080003080701030006
Content-Type: text/plain; charset=ISO-8859-1
Content-Transfer-Encoding: 7bit

chas williams - CONTRACTOR wrote:

> when you get a broken file, turn on fstrace and make an access to
> that file.  stop fstrace, fstrace dump the output and then get a 
> copy of the cache manager contents with 'cmdebug localhost 7001 -long'
> 'fs examine' on the file would be useful as well.

Here are some details on this problem.  The file in question is 34M xml
file in AFS.  I'm executing the command 'nsgmls FILE' and I get back an
error that pretty much indicates nsgmls is seeing an empty file.  I've
attached the output of fstrace while accessing the file.  I performed
this on a very quiet system so the fstrace output is only this
particular file.

I've tried this on a volume on a 1.2 filserver and a 1.4.0 fileserver
with the same results.  Does this shed any light on the problem?

The client is running 1.4.2fc3 on Linux 2.6.17 SMP (2.6.17_1.2187_FC5smp).

- Jason



--------------040407080003080701030006
Content-Type: text/plain;
 name="nsgmls-fstrace-output.txt"
Content-Transfer-Encoding: 7bit
Content-Disposition: inline;
 filename="nsgmls-fstrace-output.txt"

AFS Trace Dump -

   Date: Mon Sep 18 23:16:30 2006

Found 1 logs.

Contents of log cmfx:
time 237.426429, pid 2981: Access vp 0xf38cac80 mode 0x40 len (0x0, 0x1000) 
time 237.426446, pid 2981: GetdCache vp 0xf38cac80 dcache 0xf8ca0700 dcache low-version 0xc4b98, vcache low-version 0xc4b98 
time 237.426447, pid 2981: GetdCache tlen 0x1000 flags 0x1 abyte (0x0, 0x0) Position (0x0, 0x0) 
time 237.426453, pid 2981: Lookup adp 0xf38cac80 name catalog fid (1:536871224.-110955168.-1067354100), code=2 
time 237.426455, pid 2981: Returning code 2 from 19 
time 237.432959, pid 2981: Access vp 0xf38cac80 mode 0x40 len (0x0, 0x1000) 
time 237.432965, pid 2981: Access vp 0xf6059080 mode 0x100 len (0x0, 0x2119d6a) 
time 237.432971, pid 2981: Open 0xf6059080 flags 0x0 
time 237.432973, pid 2981: Open 0xf6059080 flags 0xf423f 
time 237.432980, pid 2981: Getattr vp 0xf6059080 len (0x0, 0x2119d6a) 
time 237.432985, pid 2981: Iread ip xf6059080 pos (0x0, 0x0) count 0x0 code 1869f 
time 237.432987, pid 2981: Iread ip xf6059080 pos (0x0, 0x0) count 0x0 code 0 
time 237.432991, pid 2981: Close 0xf6059080 flags 0x0 
time 237.433170, pid 2981: Access vp 0xf38cac80 mode 0x40 len (0x0, 0x1000) 
time 237.433178, pid 2981: GetdCache vp 0xf38cac80 dcache 0xf8ca0700 dcache low-version 0xc4b98, vcache low-version 0xc4b98 
time 237.433179, pid 2981: GetdCache tlen 0x1000 flags 0x1 abyte (0x0, 0x0) Position (0x0, 0x0) 
time 237.433182, pid 2981: Lookup adp 0xf38cac80 name catalog fid (1:536871224.-110955168.-1067354100), code=2 
time 237.433184, pid 2981: Returning code 2 from 19 
time 237.439302, pid 2981: Access vp 0xf38cac80 mode 0x40 len (0x0, 0x1000) 
time 237.439306, pid 2981: Access vp 0xf6059080 mode 0x100 len (0x0, 0x2119d6a) 
time 237.439310, pid 2981: Open 0xf6059080 flags 0x0 
time 237.439312, pid 2981: Open 0xf6059080 flags 0xf423f 
time 237.439318, pid 2981: Getattr vp 0xf6059080 len (0x0, 0x2119d6a) 
time 237.439327, pid 2981: Getattr vp 0xf6059080 len (0x0, 0x2119d6a) 
time 237.439331, pid 2981: Iread ip xf6059080 pos (0x0, 0x0) count 0x0 code 1869f 
time 237.439333, pid 2981: Iread ip xf6059080 pos (0x0, 0x0) count 0x0 code 0 
time 237.454574, pid 2981: Close 0xf6059080 flags 0x0 

AFS Trace Dump - Completed

--------------040407080003080701030006--