[svn.haxx.se] · SVN Dev · SVN Users · SVN Org · TSVN Dev · TSVN Users · Subclipse Dev · Subclipse Users · this month's index

RE: TSVNChace in endless loop on network file system: 1.5.6, Build 14908

From: Jay Kuhn <jay.kuhn_at_impinj.com>
Date: Wed, 14 Jan 2009 20:55:20 -0800

Hi Stefan,

 

Good debugging advice. Terry, thanks for jumping in also. I couldn't respond yesterday.

Stefen, I installed process mon, filtered, watched the TSVN window etc your recommended to Terry. Here is what I did and saw.

 

1. Log out, Log in

2. Setup the process monitor (PM) and TSVNCahe file crawl window, task manager etc to wathc cpu time (CPU)

 

TSNV is not taking any CPU, no PM events, no crawling messages.

 

3. Open the explorer window to my "problem" SVN work area. I tried not to touch my keyboard or mouse any more at this point.

 

4. Now, interesting..

 

  - My browswer opened really slowly on the folder. I think redrew a few times. Didn't notice any flashing icons this time but it the basic issues, things are really slow.

--TSVN CPU up to the 10-20%

- Continuous events in process monitor, I filtered out all registery events. These are all file IO. opening and closing files in by svn constantly.

   - NO ACTIVITY on the crawler window. Very interesting.

   - I think my explorer window is sometimes

       redrawing. I'm not touching anything, just mainly watching the process monitor.

 

 

OK, after about 2 minutes I saved the process monitor as a CSV file. 108,000 events over about 3 minutes (7:55:07.3766917 PM -7:57:59.5240263 PM)

 

I brought in 64K lines to excel and sorted on path. I think it is stuck in a loop. It is visiting many, many different files. Most of them are the "entries" files but there are others. It is going to TSVNCache.exe of course but that is not interesting.

 

All I can do here is scan around a little and send you some observations I make. The whole file is 20MB. Let me know if there is anything specific you want or if you want the whole file tell me where to FTP.

 

Example file: T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5t_sim\.svn\entries)

 

 

Here is a small snipet of th the entries for this files total of 1600 entries spanning most of the 3 minutes.

7:55:09 PM

TSVNCache.exe

2420

CreateFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Desired Access: Generic Read, Disposition: Open, Options: Synchronous IO Non-Alert, Non-Directory File, Attributes: n/a, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened

7:55:09 PM

TSVNCache.exe

2420

CreateFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Desired Access: Read Attributes, Synchronize, Disposition: Open, Options: Synchronous IO Non-Alert, Non-Directory File, Complete If Oplocked, Random Access, Attributes: N, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened

7:55:09 PM

TSVNCache.exe

2420

QueryStandardInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

FAST IO DISALLOWED

7:55:09 PM

TSVNCache.exe

2420

QueryStandardInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

AllocationSize: 4,096, EndOfFile: 1,310, NumberOfLinks: 1, DeletePending: False, Directory: False

7:55:09 PM

TSVNCache.exe

2420

QueryBasicInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

FAST IO DISALLOWED

7:55:09 PM

TSVNCache.exe

2420

QueryBasicInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

CreationTime: 12/15/2008 7:57:46 PM, LastAccessTime: 1/14/2009 7:54:09 PM, LastWriteTime: 12/15/2008 7:57:46 PM, ChangeTime: 12/15/2008 7:57:46 PM, FileAttributes: RA

7:55:09 PM

TSVNCache.exe

2420

QueryFileInternalInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

IndexNumber: 0xe7a56008

7:55:09 PM

TSVNCache.exe

2420

CloseFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

7:55:09 PM

TSVNCache.exe

2420

CreateFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Desired Access: Generic Read, Write Attributes, Disposition: Open, Options: Synchronous IO Non-Alert, Non-Directory File, Complete If Oplocked, Random Access, Attributes: N, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened

7:55:09 PM

TSVNCache.exe

2420

QueryBasicInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

FAST IO DISALLOWED

7:55:09 PM

TSVNCache.exe

2420

QueryBasicInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

CreationTime: 12/15/2008 7:57:46 PM, LastAccessTime: 1/14/2009 7:54:09 PM, LastWriteTime: 12/15/2008 7:57:46 PM, ChangeTime: 12/15/2008 7:57:46 PM, FileAttributes: RA

7:55:09 PM

TSVNCache.exe

2420

SetBasicInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

CreationTime: -1, LastAccessTime: -1, LastWriteTime: -1, ChangeTime: -1, FileAttributes: n/a

7:55:09 PM

TSVNCache.exe

2420

ReadFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Offset: 0, Length: 256

7:55:09 PM

TSVNCache.exe

2420

ReadFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Offset: 0, Length: 1,310, I/O Flags: Non-cached, Paging I/O, Synchronous Paging I/O

About 20 more repeats of this

7:55:09 PM

TSVNCache.exe

2420

ReadFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Offset: 0, Length: 512

7:55:09 PM

TSVNCache.exe

2420

ReadFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Offset: 0, Length: 64

7:55:09 PM

TSVNCache.exe

2420

ReadFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Offset: 0, Length: 512

7:55:09 PM

TSVNCache.exe

2420

ReadFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Offset: 1,024, Length: 286

7:55:09 PM

TSVNCache.exe

2420

ReadFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Offset: 0, Length: 1,310

7:55:09 PM

TSVNCache.exe

2420

ReadFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Offset: 0, Length: 4

7:55:09 PM

TSVNCache.exe

2420

QueryStandardInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

FAST IO DISALLOWED

7:55:09 PM

TSVNCache.exe

2420

QueryStandardInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

AllocationSize: 4,096, EndOfFile: 1,310, NumberOfLinks: 1, DeletePending: False, Directory: False

7:55:09 PM

TSVNCache.exe

2420

CloseFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

7:55:09 PM

TSVNCache.exe

2420

CreateFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Desired Access: Read Attributes, Write Attributes, Disposition: Open, Options: , Attributes: n/a, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened

7:55:09 PM

TSVNCache.exe

2420

QueryBasicInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

CreationTime: 12/15/2008 7:57:46 PM, LastAccessTime: 1/14/2009 7:56:36 PM, LastWriteTime: 12/15/2008 7:57:46 PM, ChangeTime: 12/15/2008 7:57:46 PM, FileAttributes: RA

7:55:09 PM

TSVNCache.exe

2420

CloseFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

7:55:09 PM

TSVNCache.exe

2420

ReadFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Offset: 0, Length: 80

7:55:09 PM

TSVNCache.exe

2420

ReadFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Offset: 0, Length: 1,310, I/O Flags: Non-cached, Paging I/O, Synchronous Paging I/O

7:55:09 PM

TSVNCache.exe

2420

CloseFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

7:55:11 PM

TSVNCache.exe

2420

CreateFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Desired Access: Generic Read, Disposition: Open, Options: Synchronous IO Non-Alert, Non-Directory File, Attributes: n/a, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened

7:55:11 PM

TSVNCache.exe

2420

CreateFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\mx5rf_sim\.svn\entries

SUCCESS

Desired Access: Read Attributes, Synchronize, Disposition: Open, Options: Synchronous IO Non-Alert, Non-Directory File, Complete If Oplocked, Random Access, Attributes: N, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened

 

That example snippet above just goes on like that for the whole duration of the capture. It just continues like this for the whole three minutes. But it is doing this many other files also, so I assume in each second is rapidly looping around and coming back to this one many times a second. The only error I see bleow s FAST_IO_DISALLOWED.

 

 

I do so it is looking at some non-version files at the problem level of my hierarchy and some different results that look "troubling".

 

7:55:09 PM

TSVNCache.exe

2420

QueryOpen

T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state

FAST IO DISALLOWED

7:55:09 PM

TSVNCache.exe

2420

CreateFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state

SUCCESS

Desired Access: Read Attributes, Disposition: Open, Options: Open For Backup, Open Reparse Point, Attributes: n/a, ShareMode: Read, Write, Delete, AllocationSize: n/a, OpenResult: Opened

7:55:09 PM

TSVNCache.exe

2420

QueryNetworkOpenInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state

FAST IO DISALLOWED

7:55:09 PM

TSVNCache.exe

2420

QueryNetworkOpenInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state

INVALID PARAMETER

7:55:09 PM

TSVNCache.exe

2420

QueryBasicInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state

SUCCESS

CreationTime: 12/15/2008 1:27:24 PM, LastAccessTime: 12/15/2008 7:57:29 PM, LastWriteTime: 12/15/2008 10:36:25 PM, ChangeTime: 12/15/2008 10:36:25 PM, FileAttributes: A

7:55:09 PM

TSVNCache.exe

2420

QueryStandardInformationFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state

SUCCESS

AllocationSize: 16,384, EndOfFile: 14,761, NumberOfLinks: 1, DeletePending: False, Directory: False

7:55:09 PM

TSVNCache.exe

2420

CloseFile

T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state

SUCCESS

7:55:11 PM

TSVNCache.exe

2420

QueryOpen

T:\jkuhn\designs\RFID\MX5_6p1\dfII\.drc.Last.state

FAST IO DISALLOWED

 

 

 

Hope this is of some value,

 

Jay Kuhn

 

 

 

-----Original Message-----
From: Stefan K√ľng [mailto:tortoisesvn_at_gmail.com]
Sent: Wednesday, January 14, 2009 8:24 AM
To: users_at_tortoisesvn.tigris.org
Subject: Re: TSVNChace in endless loop on network file system: 1.5.6, Build 14908

 

www.My-Software.co.uk - Sales wrote:

> Hi

>

> Did this and nothing happens, while it does this. However if I shutdown

> TSVNCache.exe and then it re-opens when I open explorer at root??? But I

> don't have any .svn folders in root and this is when it keeps freshing

> explorer and giving this issue.

 

The cache is restarted because the explorer does not know whether a

folder is versioned or not. That's why it starts the cache and asks the

cache for that information. It doesn't matter whether you have a working

copy on the root or not - the cache is started anyway.

 

Stefan

 

-- 
       ___
  oo  // \\      "De Chelonian Mobile"
 (_,\/ \_/ \     TortoiseSVN
   \ \_/_\_/>    The coolest Interface to (Sub)Version Control
   /_/   \_\     http://tortoisesvn.net
 
------------------------------------------------------
http://tortoisesvn.tigris.org/ds/viewMessage.do?dsForumId=4061&dsMessageId=1024417
 
To unsubscribe from this discussion, e-mail: [users-unsubscribe_at_tortoisesvn.tigris.org].
------------------------------------------------------
http://tortoisesvn.tigris.org/ds/viewMessage.do?dsForumId=4061&dsMessageId=1025858
To unsubscribe from this discussion, e-mail: [users-unsubscribe_at_tortoisesvn.tigris.org].
Received on 2009-01-15 07:07:10 CET

This is an archived mail posted to the TortoiseSVN Users mailing list.