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
