Below is a bash script that shows the issue. It appears that if the file
name is different, the rename works correctly (ncache invalidated for
orig_file). However, if the name of the file is the same, the ncache appears
to stick around until the timeout period.
directory=$1
#orig_file="${1}/parent_directory/sub_directory/original-file-name"
#new_file="${1}/parent_directory/new-file-name"
orig_file="${1}/parent_directory/sub_directory/file-name"
new_file="${1}/parent_directory/file-name"
mkdir -p ${1}/parent_directory/sub_directory
touch ${orig_file}
mv ${orig_file} ${new_file}
if [ -f "${orig_file}" ]; then
logger "FAIL -Found file ${orig_file} when it should not be there"
else
logger "SUCCESS - file ${orig_file} is not there"
fi
if [ -f ${new_file} ]; then
logger "SUCCESS - Found ${new_file} and it should be there"
else
logger "FAIL - Could not find ${new_file}"
fi
-----Original Message-----
From: David Metheny [mailto:[email protected]]
Sent: Friday, January 07, 2011 1:29 PM
To: 'Phil Carns'; '[email protected]'
Subject: RE: [Pvfs2-developers] PVFS 2.8 and Subversion checkout issue
I'm looking through this now also, but here is the ncache debugging
messages. There are lots of other files, but the one with the error is
"PVFS2/.svn/tmp/entries".
2011-01-07T13:26:49.977062-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [dmethe]
2011-01-07T13:26:49.977078-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[dmethe]
2011-01-07T13:26:49.977084-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of dmethe
2011-01-07T13:26:49.977465-06:00 vm90093 PVFS2: [D] *** ncache update on
dmethe target (1045792|1124732824) parent (1048576|1124732824)
2011-01-07T13:26:49.977473-06:00 vm90093 PVFS2: [D] ncache: update(): name
[dmethe]
2011-01-07T13:26:49.977477-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:49.977796-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [dmethe]
2011-01-07T13:26:49.977812-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:49.977817-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:49.977827-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of dmethe (1045792|1124732824)
2011-01-07T13:26:55.258463-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.258478-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[PVFS2]
2011-01-07T13:26:55.258483-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of PVFS2
2011-01-07T13:26:55.260685-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.260694-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[PVFS2]
2011-01-07T13:26:55.260698-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of PVFS2
2011-01-07T13:26:55.263951-06:00 vm90093 PVFS2: [D] ncache: update(): name
[PVFS2]
2011-01-07T13:26:55.263959-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.264310-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.264318-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.264337-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.264342-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.264411-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.264418-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[.svn]
2011-01-07T13:26:55.264422-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of .svn
2011-01-07T13:26:55.264703-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.264710-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.264714-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.264718-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.264811-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.264818-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[.svn]
2011-01-07T13:26:55.264835-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of .svn
2011-01-07T13:26:55.267954-06:00 vm90093 PVFS2: [D] ncache: update(): name
[.svn]
2011-01-07T13:26:55.267962-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.268303-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.268310-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.268314-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.268318-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.268631-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.268638-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.268642-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.268646-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.268725-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [lock]
2011-01-07T13:26:55.268732-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[lock]
2011-01-07T13:26:55.268736-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of lock
2011-01-07T13:26:55.271387-06:00 vm90093 PVFS2: [D] ncache: update(): name
[lock]
2011-01-07T13:26:55.271395-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.271763-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.271770-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.271776-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.271792-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.271879-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.271885-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.271889-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.271893-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.272223-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [lock]
2011-01-07T13:26:55.272230-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.272234-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.272238-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of lock (1045733|1124732824)
2011-01-07T13:26:55.272351-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.272358-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.272362-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.272366-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.272443-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.272463-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.272468-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.272472-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.272546-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [text-base]
2011-01-07T13:26:55.272552-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[text-base]
2011-01-07T13:26:55.272556-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of text-base
2011-01-07T13:26:55.275438-06:00 vm90093 PVFS2: [D] ncache: update(): name
[text-base]
2011-01-07T13:26:55.275463-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.275785-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.275792-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.275796-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.275800-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.275890-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.275896-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.275900-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.275904-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.276242-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [lock]
2011-01-07T13:26:55.276250-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.276254-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.276258-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of lock (1045733|1124732824)
2011-01-07T13:26:55.276367-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.276374-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.276378-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.276382-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.276466-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.276473-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.276477-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.276481-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.276558-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [prop-base]
2011-01-07T13:26:55.276566-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[prop-base]
2011-01-07T13:26:55.276570-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of prop-base
2011-01-07T13:26:55.279322-06:00 vm90093 PVFS2: [D] ncache: update(): name
[prop-base]
2011-01-07T13:26:55.279329-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.279638-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.279645-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.279649-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.279653-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.279734-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.279741-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.279745-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.279748-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.280074-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [lock]
2011-01-07T13:26:55.280081-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.280084-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.280088-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of lock (1045733|1124732824)
2011-01-07T13:26:55.280198-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.280205-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.280209-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.280213-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.280289-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.280296-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.280299-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.280303-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.280381-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [props]
2011-01-07T13:26:55.280387-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[props]
2011-01-07T13:26:55.280391-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of props
2011-01-07T13:26:55.283058-06:00 vm90093 PVFS2: [D] ncache: update(): name
[props]
2011-01-07T13:26:55.283066-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.283351-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.283359-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.283364-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.283370-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.283462-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.283469-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.283473-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.283477-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.283776-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [lock]
2011-01-07T13:26:55.283783-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.283787-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.283791-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of lock (1045733|1124732824)
2011-01-07T13:26:55.283896-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.283903-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.283906-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.283923-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.283999-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.284005-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.284009-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.284013-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.284087-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [tmp]
2011-01-07T13:26:55.284094-06:00 vm90093 PVFS2: [D] ncache: miss: name=[tmp]
2011-01-07T13:26:55.284097-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of tmp
2011-01-07T13:26:55.286779-06:00 vm90093 PVFS2: [D] ncache: update(): name
[tmp]
2011-01-07T13:26:55.286787-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.287109-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.287116-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.287120-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.287124-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.287198-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.287205-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.287209-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.287212-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.287520-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [lock]
2011-01-07T13:26:55.287527-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.287531-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.287535-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of lock (1045733|1124732824)
2011-01-07T13:26:55.287642-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.287649-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.287653-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.287657-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.287729-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.287736-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.287739-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.287743-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.287816-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [tmp]
2011-01-07T13:26:55.287822-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.287826-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.287830-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of tmp (1026639|1124732824)
2011-01-07T13:26:55.287903-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [text-base]
2011-01-07T13:26:55.287910-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[text-base]
2011-01-07T13:26:55.287914-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of text-base
2011-01-07T13:26:55.290601-06:00 vm90093 PVFS2: [D] ncache: update(): name
[text-base]
2011-01-07T13:26:55.290609-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.290898-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.290905-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.290909-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.290912-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.291004-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.291011-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.291015-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.291019-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.291093-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [lock]
2011-01-07T13:26:55.291100-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.291104-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.291108-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of lock (1045733|1124732824)
2011-01-07T13:26:55.291213-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.291220-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.291224-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.291228-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.291300-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.291307-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.291310-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.291314-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.291398-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [tmp]
2011-01-07T13:26:55.291405-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.291409-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.291413-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of tmp (1026639|1124732824)
2011-01-07T13:26:55.291751-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [prop-base]
2011-01-07T13:26:55.291758-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[prop-base]
2011-01-07T13:26:55.291762-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of prop-base
2011-01-07T13:26:55.294445-06:00 vm90093 PVFS2: [D] ncache: update(): name
[prop-base]
2011-01-07T13:26:55.294453-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.294809-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.294817-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.294820-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.294824-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.294905-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.294911-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.294915-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.294921-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.295012-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [lock]
2011-01-07T13:26:55.295020-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.295025-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.295029-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of lock (1045733|1124732824)
2011-01-07T13:26:55.295134-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.295149-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.295153-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.295157-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.295222-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.295228-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.295232-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.295236-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.295308-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [tmp]
2011-01-07T13:26:55.295314-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.295318-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.295322-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of tmp (1026639|1124732824)
2011-01-07T13:26:55.295650-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [props]
2011-01-07T13:26:55.295657-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[props]
2011-01-07T13:26:55.295661-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of props
2011-01-07T13:26:55.298397-06:00 vm90093 PVFS2: [D] ncache: update(): name
[props]
2011-01-07T13:26:55.298404-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.298802-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.298808-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.298812-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.298816-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.298896-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.298902-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.298906-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.298909-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.298983-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [tmp]
2011-01-07T13:26:55.298990-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.298993-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.299017-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of tmp (1026639|1124732824)
2011-01-07T13:26:55.299316-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [entries]
2011-01-07T13:26:55.299323-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[entries]
2011-01-07T13:26:55.299327-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of entries
2011-01-07T13:26:55.301914-06:00 vm90093 PVFS2: [D] ncache: update(): name
[entries]
2011-01-07T13:26:55.301921-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.310720-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.310729-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.310734-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.310737-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.310825-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.310831-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.310835-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.310839-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.310913-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [tmp]
2011-01-07T13:26:55.310920-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.310924-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.310928-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of tmp (1026639|1124732824)
2011-01-07T13:26:55.311222-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.311229-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.311233-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.311237-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.311314-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.311321-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.311325-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.311328-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.311413-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [entries]
2011-01-07T13:26:55.311420-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.311424-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.311428-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of entries (1045728|1124732824)
2011-01-07T13:26:55.311993-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [entries]
2011-01-07T13:26:55.312000-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[entries]
2011-01-07T13:26:55.312003-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of entries
2011-01-07T13:26:55.312251-06:00 vm90093 PVFS2: [D] ncache: invalidate():
entry=entries
2011-01-07T13:26:55.312258-06:00 vm90093 PVFS2: [D] ncache: invalidate():
entry=entries
2011-01-07T13:26:55.314502-06:00 vm90093 PVFS2: [D] ncache: update(): name
[entries]
2011-01-07T13:26:55.314510-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.314569-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.314576-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.314579-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.314583-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.314663-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.314670-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.314674-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.314678-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.314758-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [entries]
2011-01-07T13:26:55.314764-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[entries]
2011-01-07T13:26:55.314770-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of entries
2011-01-07T13:26:55.315045-06:00 vm90093 PVFS2: [D] *** ncache update on
entries target (1045728|1124732824) parent (1050339|1124732824)
2011-01-07T13:26:55.315052-06:00 vm90093 PVFS2: [D] ncache: update(): name
[entries]
2011-01-07T13:26:55.315056-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.315182-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.315189-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.315193-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.315197-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.315272-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.315278-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.315282-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.315287-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.315360-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [entries]
2011-01-07T13:26:55.315366-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.315370-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.315374-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of entries (1045728|1124732824)
2011-01-07T13:26:55.316097-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.316104-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.316108-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.316111-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.316189-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.316195-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.316199-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.316203-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.316278-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [format.tmp]
2011-01-07T13:26:55.316285-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[format.tmp]
2011-01-07T13:26:55.316289-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of format.tmp
2011-01-07T13:26:55.318897-06:00 vm90093 PVFS2: [D] ncache: update(): name
[format.tmp]
2011-01-07T13:26:55.318904-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.320808-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.320815-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.320819-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.320823-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.320901-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.320907-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.320911-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.320915-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.321250-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.321257-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.321261-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.321265-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.321343-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.321356-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.321362-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.321366-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.321442-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [format.tmp]
2011-01-07T13:26:55.321461-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.321466-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.321470-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of format.tmp (1050337|1124732824)
2011-01-07T13:26:55.322087-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [format]
2011-01-07T13:26:55.322094-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[format]
2011-01-07T13:26:55.322098-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of format
2011-01-07T13:26:55.322370-06:00 vm90093 PVFS2: [D] ncache: invalidate():
entry=format.tmp
2011-01-07T13:26:55.322377-06:00 vm90093 PVFS2: [D] ncache: invalidate():
entry=format
2011-01-07T13:26:55.324669-06:00 vm90093 PVFS2: [D] ncache: update(): name
[format]
2011-01-07T13:26:55.324677-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.324731-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.324738-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.324755-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.324759-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.324839-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.324846-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.324849-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.324853-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.324929-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [format]
2011-01-07T13:26:55.324936-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.324940-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.324943-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of format (1050337|1124732824)
2011-01-07T13:26:55.325047-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.325054-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.325058-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.325062-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.325156-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.325163-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.325167-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.325171-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.325238-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [format]
2011-01-07T13:26:55.325246-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.325249-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.325253-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of format (1050337|1124732824)
2011-01-07T13:26:55.325976-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.325983-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.325986-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.325990-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.326075-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.326081-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.326085-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.326101-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.326184-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [lock]
2011-01-07T13:26:55.326191-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.326195-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.326199-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of lock (1045733|1124732824)
2011-01-07T13:26:55.328722-06:00 vm90093 PVFS2: [D] ncache: invalidate():
entry=lock
2011-01-07T13:26:55.328807-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.328814-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[.svn]
2011-01-07T13:26:55.328818-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of .svn
2011-01-07T13:26:55.329177-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.329184-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.329188-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.329192-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.329268-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.329275-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.329279-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.329283-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.329356-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [entries]
2011-01-07T13:26:55.329363-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.329367-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.329371-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of entries (1045728|1124732824)
2011-01-07T13:26:55.340108-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.340117-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.340121-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.340125-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.340198-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.340205-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.340209-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.340213-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.340301-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [lock]
2011-01-07T13:26:55.340308-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[lock]
2011-01-07T13:26:55.340312-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of lock
2011-01-07T13:26:55.342915-06:00 vm90093 PVFS2: [D] ncache: update(): name
[lock]
2011-01-07T13:26:55.342923-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.343324-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.343331-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.343335-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.343353-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.343423-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.343430-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.343434-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.343437-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.343774-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [entries]
2011-01-07T13:26:55.343781-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.343785-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.343789-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of entries (1045728|1124732824)
2011-01-07T13:26:55.753854-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.753867-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.753872-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.753875-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.753913-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.753932-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.753948-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.753965-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.754080-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [tmp]
2011-01-07T13:26:55.754099-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.754115-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.754132-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of tmp (1026639|1124732824)
2011-01-07T13:26:55.754301-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [tempfile.tmp]
2011-01-07T13:26:55.754309-06:00 vm90093 PVFS2: [D] ncache: miss:
name=[tempfile.tmp]
2011-01-07T13:26:55.754317-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of tempfile.tmp
2011-01-07T13:26:55.757244-06:00 vm90093 PVFS2: [D] ncache: update(): name
[tempfile.tmp]
2011-01-07T13:26:55.757253-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.757707-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.757714-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.757718-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.757722-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.757846-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.757855-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.757860-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.757864-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.758507-06:00 vm90093 PVFS2: [D] ncache: update(): name
[.svn]
2011-01-07T13:26:55.758515-06:00 vm90093 PVFS2: [D] ncache: update():
return=0
2011-01-07T13:26:55.875717-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.875727-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.875731-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.875735-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.876281-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.876288-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.876292-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.876311-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.876386-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [lock]
2011-01-07T13:26:55.876395-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.876399-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.876403-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of lock (1047619|1124732824)
2011-01-07T13:26:55.876527-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.876537-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.876541-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.876545-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.876616-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.876625-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.876629-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.876633-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.876704-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [tmp]
2011-01-07T13:26:55.876714-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.876718-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.876722-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of tmp (1026639|1124732824)
2011-01-07T13:26:55.877056-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [entries]
2011-01-07T13:26:55.877066-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.877070-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.877074-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of entries (1045728|1124732824)
2011-01-07T13:26:55.877416-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.877423-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.877427-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.877431-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.877519-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.877529-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.877533-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.877537-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.877608-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [tmp]
2011-01-07T13:26:55.877617-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.877621-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.877625-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of tmp (1026639|1124732824)
2011-01-07T13:26:55.877695-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [entries]
2011-01-07T13:26:55.877705-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.877709-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.877712-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of entries (1045728|1124732824)
2011-01-07T13:26:55.878364-06:00 vm90093 PVFS2: [D] ncache: invalidate():
entry=entries
2011-01-07T13:26:55.887194-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:55.887203-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.887207-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.887211-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:55.887287-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:55.887294-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.887298-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.887302-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:55.887377-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [tmp]
2011-01-07T13:26:55.887396-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.887400-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.887404-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of tmp (1026639|1124732824)
2011-01-07T13:26:55.887478-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [tempfile.tmp]
2011-01-07T13:26:55.887497-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:55.887502-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:55.887506-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of tempfile.tmp (1045727|1124732824)
2011-01-07T13:26:55.890651-06:00 vm90093 PVFS2: [D] ncache: invalidate():
entry=tempfile.tmp
2011-01-07T13:26:57.110441-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:57.110455-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:57.110460-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:57.110464-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:57.110551-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:57.110560-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:57.110565-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:57.110570-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:57.110680-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [log]
2011-01-07T13:26:57.110699-06:00 vm90093 PVFS2: [D] ncache: miss: name=[log]
2011-01-07T13:26:57.110715-06:00 vm90093 PVFS2: [D] *** ncache clean miss on
first segment of log
2011-01-07T13:26:57.111151-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [PVFS2]
2011-01-07T13:26:57.111179-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:57.111199-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:57.111220-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of PVFS2 (1047626|1124732824)
2011-01-07T13:26:57.111349-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [.svn]
2011-01-07T13:26:57.111372-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:57.111403-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:57.111425-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of .svn (1050339|1124732824)
2011-01-07T13:26:57.111535-06:00 vm90093 PVFS2: [D] ncache:
get_cached_entry(): [lock]
2011-01-07T13:26:57.111560-06:00 vm90093 PVFS2: [D] ncache: status=0,
entry_status=0
2011-01-07T13:26:57.111580-06:00 vm90093 PVFS2: [D] ncache: copying out ref.
2011-01-07T13:26:57.111601-06:00 vm90093 PVFS2: [D] *** ncache hit on first
segment of lock (1047619|1124732824)
2011-01-07T13:26:57.114232-06:00 vm90093 PVFS2: [D] ncache: invalidate():
entry=lock
-----Original Message-----
From: [email protected]
[mailto:[email protected]] On Behalf Of Phil
Carns
Sent: Friday, January 07, 2011 11:24 AM
To: [email protected]
Subject: Re: [Pvfs2-developers] PVFS 2.8 and Subversion checkout issue
It might be interesting to enable the ncache debugging mask in
pvfs2-client-core to get some more information about what's going on
with the directory entry in question. Just glancing over sys-rename.sm
it looks like it invalidates the ncache (for both the src and
destination entry) right off the bat, so I would be curious to see if
that is working as expected (ie, if it is generating an invalidate log
message for the "entries" file), and if so if something is adding it
back into the ncache after it is invalidated.
-Phil
On 01/07/2011 10:10 AM, David Metheny wrote:
> It's 2.8.2 with a bunch of patches (things that went into 2.8 branch, plus
> some other misc that haven't made it to the branch).
>
> -----Original Message-----
> From: [email protected]
> [mailto:[email protected]] On Behalf Of
> Michael Moore
> Sent: Thursday, January 06, 2011 5:43 PM
> To: [email protected]
> Cc: [email protected]
> Subject: Re: [Pvfs2-developers] PVFS 2.8 and Subversion checkout issue
>
> On Thu, Jan 06, 2011 at 04:33:29PM -0600, David Metheny wrote:
>> I'm running PVFS 2.8 on RHEL5 x86_64 with a single server running both
>> metadata and io. I'm using a RHEL5 i386 server as a client. I'm trying to
>> checkout some code that resides in subversion and get the following
error.
>>
>>
>> svn: Can't remove file 'PVFS2/.svn/tmp/entries': No such file or
directory
>>
>>
>>
>> Doing an strace, The file is created, written to, closed, renamed. Then
>> later on an open is issued to see if it still exists, and it does
> (shouldn't
>> though). When the unlink goes to the server, it isn't there (expected).
It
>> looks like the rename command isn't flushing the ncache of the old entry.
> I
>> can turn off ncache, and the svn checkout works.
> I bet you've got the issue identified correctly. What specific version
> of 2.8 are you running (to see if it's fixed in a newer version or
> still an issue that needs a fix)?
>
> Michael
>
>>
>>
>> open("PVFS2/.svn/tmp/entries", O_WRONLY|O_CREAT|O_EXCL|O_LARGEFILE, 0666)
> =
>> 3
>>
>> write(3, "8\n\ndir\n22899\nhttps://svn.sharedd";..., 214) = 214
>>
>> close(3) = 0
>>
>> rename("PVFS2/.svn/tmp/entries", "PVFS2/.svn/entries") = 0
>>
>> < REMOVED LOTS OF LINES HERE>
>>
>> open("PVFS2/.svn/tmp/entries", O_WRONLY|O_CREAT|O_EXCL|O_LARGEFILE, 0666)
> =
>> -1 EEXIST (File exists)
>>
>> unlink("PVFS2/.svn/tmp/entries") = -1 ENOENT (No such file or
>> directory)
>>
>>
>>
>> Is there some place in the sys-rename.sm that is failing to remove the
old
>> entry?
>>
>>
>>
>> _______________________________________________
>> Pvfs2-developers mailing list
>> [email protected]
>> http://www.beowulf-underground.org/mailman/listinfo/pvfs2-developers
> _______________________________________________
> Pvfs2-developers mailing list
> [email protected]
> http://www.beowulf-underground.org/mailman/listinfo/pvfs2-developers
>
> _______________________________________________
> Pvfs2-developers mailing list
> [email protected]
> http://www.beowulf-underground.org/mailman/listinfo/pvfs2-developers
_______________________________________________
Pvfs2-developers mailing list
[email protected]
http://www.beowulf-underground.org/mailman/listinfo/pvfs2-developers
_______________________________________________
Pvfs2-developers mailing list
[email protected]
http://www.beowulf-underground.org/mailman/listinfo/pvfs2-developers
